builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0117 starttime: 1464887782.67 results: success (0) buildid: 20160602084252 builduid: 4bfe8edb776e4bec8df9594c77510e96 revision: 0979bb255f40483c20acd85843db49a4694f72f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:22.672819) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:22.673281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:22.673579) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:22.696382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:22.696848) ========= 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.5QgYsJhY0L/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.svK5jVQkUC/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-06-02 10:16:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2016-06-02 10:16:23 (15.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.315199 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:23.032468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:23.032796) ========= 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.5QgYsJhY0L/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.svK5jVQkUC/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.036530 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:23.087678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 10:16:23.088030) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0979bb255f40483c20acd85843db49a4694f72f6 --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 0979bb255f40483c20acd85843db49a4694f72f6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5QgYsJhY0L/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.svK5jVQkUC/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-06-02 10:16:23,164 truncating revision to first 12 chars 2016-06-02 10:16:23,164 Setting DEBUG logging. 2016-06-02 10:16:23,164 attempt 1/10 2016-06-02 10:16:23,165 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0979bb255f40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 10:16:25,383 unpacking tar archive at: fx-team-0979bb255f40/testing/mozharness/ program finished with exit code 0 elapsedTime=2.475236 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 10:16:25.584429) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:25.584971) ========= script_repo_revision: 0979bb255f40483c20acd85843db49a4694f72f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:25.585548) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:25.585999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 10:16:25.603307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-06-02 10:16:25.603855) ========= /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.5QgYsJhY0L/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.svK5jVQkUC/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:16:25 INFO - MultiFileLogger online at 20160602 10:16:25 in /builds/slave/test 10:16:25 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 10:16:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:16:25 INFO - {'append_to_log': False, 10:16:25 INFO - 'base_work_dir': '/builds/slave/test', 10:16:25 INFO - 'blob_upload_branch': 'Fx-Team', 10:16:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:16:25 INFO - 'branch': 'Fx-Team', 10:16:25 INFO - 'buildbot_json_path': 'buildprops.json', 10:16:25 INFO - 'config_files': ('talos/mac_config.py',), 10:16:25 INFO - 'default_actions': ('clobber', 10:16:25 INFO - 'read-buildbot-config', 10:16:25 INFO - 'download-and-extract', 10:16:25 INFO - 'populate-webroot', 10:16:25 INFO - 'create-virtualenv', 10:16:25 INFO - 'install', 10:16:25 INFO - 'run-tests'), 10:16:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:16:25 INFO - 'download_minidump_stackwalk': True, 10:16:25 INFO - 'download_symbols': 'ondemand', 10:16:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:16:25 INFO - 'tooltool.py': '/tools/tooltool.py', 10:16:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:16:25 INFO - '/tools/misc-python/virtualenv.py')}, 10:16:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:16:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:16:25 INFO - 'installer_path': 'installer.exe', 10:16:25 INFO - 'log_level': 'info', 10:16:25 INFO - 'log_name': 'talos', 10:16:25 INFO - 'log_to_console': True, 10:16:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:16:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:16:25 INFO - 'opt_config_files': (), 10:16:25 INFO - 'pip_index': False, 10:16:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:16:25 INFO - 'cmd': ('bash', 10:16:25 INFO - '-c', 10:16:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:16:25 INFO - 'enabled': True, 10:16:25 INFO - 'halt_on_failure': False, 10:16:25 INFO - 'name': 'check_screen_resolution'},), 10:16:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:16:25 INFO - 'cmd': ('bash', 10:16:25 INFO - '-c', 10:16:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:16:25 INFO - 'enabled': True, 10:16:25 INFO - 'halt_on_failure': False, 10:16:25 INFO - 'name': 'check_screen_resolution'},), 10:16:25 INFO - 'run_cmd_checks_enabled': True, 10:16:25 INFO - 'sps_profile': False, 10:16:25 INFO - 'sps_profile_interval': 0, 10:16:25 INFO - 'suite': 'dromaeojs', 10:16:25 INFO - 'system_bits': '32', 10:16:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:16:25 INFO - 'title': 't-yosemite-r7-0117', 10:16:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:16:25 INFO - 'use_talos_json': True, 10:16:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:16:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:16:25 INFO - 'work_dir': 'build'} 10:16:25 INFO - ##### 10:16:25 INFO - ##### Running clobber step. 10:16:25 INFO - ##### 10:16:25 INFO - Running pre-action listener: _resource_record_pre_action 10:16:25 INFO - Running main action method: clobber 10:16:25 INFO - rmtree: /builds/slave/test/build 10:16:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:16:27 INFO - Running post-action listener: _resource_record_post_action 10:16:27 INFO - ##### 10:16:27 INFO - ##### Finished clobber step (success) 10:16:27 INFO - ##### 10:16:27 INFO - ##### 10:16:27 INFO - ##### Running read-buildbot-config step. 10:16:27 INFO - ##### 10:16:27 INFO - Running pre-action listener: _resource_record_pre_action 10:16:27 INFO - Running main action method: read_buildbot_config 10:16:27 INFO - Using buildbot properties: 10:16:27 INFO - { 10:16:27 INFO - "project": "", 10:16:27 INFO - "product": "firefox", 10:16:27 INFO - "script_repo_revision": "production", 10:16:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:16:27 INFO - "repository": "", 10:16:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:16:27 INFO - "buildid": "20160602084252", 10:16:27 INFO - "pgo_build": "False", 10:16:27 INFO - "basedir": "/builds/slave/test", 10:16:27 INFO - "buildnumber": 45, 10:16:27 INFO - "slavename": "t-yosemite-r7-0117", 10:16:27 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:16:27 INFO - "platform": "yosemite_r7", 10:16:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:16:27 INFO - "branch": "fx-team", 10:16:27 INFO - "repo_path": "integration/fx-team", 10:16:27 INFO - "revision": "0979bb255f40483c20acd85843db49a4694f72f6", 10:16:27 INFO - "stage_platform": "macosx64", 10:16:27 INFO - "builduid": "4bfe8edb776e4bec8df9594c77510e96", 10:16:27 INFO - "slavebuilddir": "test" 10:16:27 INFO - } 10:16:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:16:27 INFO - Running post-action listener: _resource_record_post_action 10:16:27 INFO - ##### 10:16:27 INFO - ##### Finished read-buildbot-config step (success) 10:16:27 INFO - ##### 10:16:27 INFO - ##### 10:16:27 INFO - ##### Running download-and-extract step. 10:16:27 INFO - ##### 10:16:27 INFO - Running pre-action listener: _resource_record_pre_action 10:16:27 INFO - Running main action method: download_and_extract 10:16:27 INFO - mkdir: /builds/slave/test/build/tests 10:16:27 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')]} 10:16:27 INFO - https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:16:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:16:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:16:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:29 INFO - Downloaded 1199 bytes. 10:16:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:16:29 INFO - Using the following test package requirements: 10:16:29 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:16:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:16:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:16:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'jsshell-mac.zip'], 10:16:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:16:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:16:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:16:29 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:16:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:16:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:16:29 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:16:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:29 INFO - https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:16:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:16:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:16:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:30 INFO - Downloaded 18713567 bytes. 10:16: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'] 10:16: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 10:16:33 INFO - Return code: 0 10:16:33 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 10:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:33 INFO - https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:16:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:16:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:16:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:34 INFO - Downloaded 18713567 bytes. 10:16:34 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'] 10:16:34 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 10:16:38 INFO - Return code: 0 10:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:38 INFO - https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:16:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:16:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:16:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:41 INFO - Downloaded 10834055 bytes. 10:16:41 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'] 10:16:41 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 10:16:42 INFO - Return code: 0 10:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:42 INFO - https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:16:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:16:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:16:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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 10:16:45 INFO - Downloaded 111080397 bytes. 10:16:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:16:45 INFO - mkdir: /builds/slave/test/properties 10:16:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:16:45 INFO - Writing to file /builds/slave/test/properties/build_url 10:16:45 INFO - Contents: 10:16:45 INFO - build_url:https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:16:45 INFO - Running post-action listener: _resource_record_post_action 10:16:45 INFO - Running post-action listener: set_extra_try_arguments 10:16:45 INFO - ##### 10:16:45 INFO - ##### Finished download-and-extract step (success) 10:16:45 INFO - ##### 10:16:45 INFO - ##### 10:16:45 INFO - ##### Running populate-webroot step. 10:16:45 INFO - ##### 10:16:45 INFO - Running pre-action listener: _resource_record_pre_action 10:16:45 INFO - Running main action method: populate_webroot 10:16:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:16:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:16:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:16:45 INFO - '--tppagecycles', 10:16:45 INFO - '7'], 10:16:45 INFO - 'tests': ['tsvgm']}}, 10:16:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:16:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:16:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:16:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:16:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:16:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:16:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['damp', 'tps']}, 10:16:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'tests': ['damp', 'tps']}, 10:16:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['dromaeo_dom']}, 10:16:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:16:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['a11yr', 10:16:45 INFO - 'ts_paint', 10:16:45 INFO - 'tpaint', 10:16:45 INFO - 'sessionrestore', 10:16:45 INFO - 'sessionrestore_no_auto_restore', 10:16:45 INFO - 'tabpaint']}, 10:16:45 INFO - 'other-e10s': {'tests': ['a11yr', 10:16:45 INFO - 'ts_paint', 10:16:45 INFO - 'tpaint', 10:16:45 INFO - 'sessionrestore', 10:16:45 INFO - 'sessionrestore_no_auto_restore', 10:16:45 INFO - 'tabpaint']}, 10:16:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:16:45 INFO - 'ts_paint', 10:16:45 INFO - 'tpaint', 10:16:45 INFO - 'sessionrestore', 10:16:45 INFO - 'sessionrestore_no_auto_restore', 10:16:45 INFO - 'tabpaint']}, 10:16:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:16:45 INFO - 'ts_paint', 10:16:45 INFO - 'tpaint', 10:16:45 INFO - 'sessionrestore', 10:16:45 INFO - 'sessionrestore_no_auto_restore', 10:16:45 INFO - 'tabpaint']}, 10:16:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['a11yr', 10:16:45 INFO - 'ts_paint', 10:16:45 INFO - 'tpaint', 10:16:45 INFO - 'sessionrestore', 10:16:45 INFO - 'sessionrestore_no_auto_restore', 10:16:45 INFO - 'tabpaint']}, 10:16:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['a11yr', 10:16:45 INFO - 'ts_paint', 10:16:45 INFO - 'tpaint', 10:16:45 INFO - 'sessionrestore', 10:16:45 INFO - 'sessionrestore_no_auto_restore', 10:16:45 INFO - 'tabpaint']}, 10:16:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['tsvgx', 10:16:45 INFO - 'tsvgr_opacity', 10:16:45 INFO - 'tart', 10:16:45 INFO - 'tscrollx', 10:16:45 INFO - 'cart']}, 10:16:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:16:45 INFO - 'tsvgr_opacity', 10:16:45 INFO - 'tart', 10:16:45 INFO - 'tscrollx', 10:16:45 INFO - 'cart']}, 10:16:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'talos_options': ['--disable-e10s'], 10:16:45 INFO - 'tests': ['tp5o']}, 10:16:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'tests': ['tp5o']}, 10:16:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'talos_options': ['--disable-e10s', 10:16:45 INFO - '--xperf_path', 10:16:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:16:45 INFO - 'tests': ['tp5n']}, 10:16:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:45 INFO - 'talos_options': ['--xperf_path', 10:16:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:16:45 INFO - 'tests': ['tp5n']}}, 10:16:45 INFO - 'talos.zip': {'path': '', 10:16:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:16:45 INFO - Running post-action listener: _resource_record_post_action 10:16:45 INFO - ##### 10:16:45 INFO - ##### Finished populate-webroot step (success) 10:16:45 INFO - ##### 10:16:45 INFO - ##### 10:16:45 INFO - ##### Running create-virtualenv step. 10:16:45 INFO - ##### 10:16:45 INFO - Running pre-action listener: _resource_record_pre_action 10:16:45 INFO - Running main action method: create_virtualenv 10:16:45 INFO - Creating virtualenv /builds/slave/test/build/venv 10:16:45 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 10:16:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:16:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:16:45 INFO - Using real prefix '/tools/python27' 10:16:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:16:46 INFO - Installing distribute.............................................................................................................................................................................................done. 10:16:49 INFO - Installing pip.................done. 10:16:49 INFO - Return code: 0 10:16:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:49 INFO - 'HOME': '/Users/cltbld', 10:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:49 INFO - 'LOGNAME': 'cltbld', 10:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:49 INFO - 'MOZ_NO_REMOTE': '1', 10:16:49 INFO - 'NO_EM_RESTART': '1', 10:16:49 INFO - 'PAGER': '/bin/cat', 10:16:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:49 INFO - 'PWD': '/builds/slave/test', 10:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:49 INFO - 'SHELL': '/bin/bash', 10:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:16:49 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:49 INFO - 'USER': 'cltbld', 10:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:49 INFO - 'XPC_FLAGS': '0x0', 10:16:49 INFO - 'XPC_SERVICE_NAME': '0', 10:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:16:49 INFO - Cleaning up... 10:16:49 INFO - Return code: 0 10:16:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:49 INFO - 'HOME': '/Users/cltbld', 10:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:49 INFO - 'LOGNAME': 'cltbld', 10:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:49 INFO - 'MOZ_NO_REMOTE': '1', 10:16:49 INFO - 'NO_EM_RESTART': '1', 10:16:49 INFO - 'PAGER': '/bin/cat', 10:16:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:49 INFO - 'PWD': '/builds/slave/test', 10:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:49 INFO - 'SHELL': '/bin/bash', 10:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:16:49 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:49 INFO - 'USER': 'cltbld', 10:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:49 INFO - 'XPC_FLAGS': '0x0', 10:16:49 INFO - 'XPC_SERVICE_NAME': '0', 10:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:50 INFO - Downloading/unpacking psutil>=3.1.1 10:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:16:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:52 INFO - Installing collected packages: psutil 10:16:52 INFO - Running setup.py install for psutil 10:16:52 INFO - building 'psutil._psutil_osx' extension 10:16:52 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 10:16:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:16:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:16:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:16:53 INFO - building 'psutil._psutil_posix' extension 10:16:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:16:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:16:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:16:53 INFO - ^ 10:16:53 INFO - 1 warning generated. 10:16:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:16:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:53 INFO - Successfully installed psutil 10:16:53 INFO - Cleaning up... 10:16:53 INFO - Return code: 0 10:16:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:53 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 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:16:53 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 10:16:53 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 10:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:53 INFO - 'HOME': '/Users/cltbld', 10:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:53 INFO - 'LOGNAME': 'cltbld', 10:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:53 INFO - 'MOZ_NO_REMOTE': '1', 10:16:53 INFO - 'NO_EM_RESTART': '1', 10:16:53 INFO - 'PAGER': '/bin/cat', 10:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:53 INFO - 'PWD': '/builds/slave/test', 10:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:53 INFO - 'SHELL': '/bin/bash', 10:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:16:53 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:53 INFO - 'USER': 'cltbld', 10:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:53 INFO - 'XPC_FLAGS': '0x0', 10:16:53 INFO - 'XPC_SERVICE_NAME': '0', 10:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:16: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 10:16: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 10:16: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 10:16: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 10:16: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 10:16: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 10:16:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:16:56 INFO - Installing collected packages: mozsystemmonitor 10:16:56 INFO - Running setup.py install for mozsystemmonitor 10:16:56 INFO - Successfully installed mozsystemmonitor 10:16:56 INFO - Cleaning up... 10:16:56 INFO - Return code: 0 10:16:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:16:56 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')]} 10:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:56 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')]} 10:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:56 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 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:16:56 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 10:16:56 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 10:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:56 INFO - 'HOME': '/Users/cltbld', 10:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:56 INFO - 'LOGNAME': 'cltbld', 10:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:56 INFO - 'MOZ_NO_REMOTE': '1', 10:16:56 INFO - 'NO_EM_RESTART': '1', 10:16:56 INFO - 'PAGER': '/bin/cat', 10:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:56 INFO - 'PWD': '/builds/slave/test', 10:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:56 INFO - 'SHELL': '/bin/bash', 10:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:16:56 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:56 INFO - 'USER': 'cltbld', 10:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:56 INFO - 'XPC_FLAGS': '0x0', 10:16:56 INFO - 'XPC_SERVICE_NAME': '0', 10:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:57 INFO - Downloading/unpacking blobuploader==1.2.4 10:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:59 INFO - Downloading blobuploader-1.2.4.tar.gz 10:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:16:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:17:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:00 INFO - Downloading docopt-0.6.1.tar.gz 10:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:17:00 INFO - Installing collected packages: blobuploader, requests, docopt 10:17:00 INFO - Running setup.py install for blobuploader 10:17:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:17:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:17:01 INFO - Running setup.py install for requests 10:17:01 INFO - Running setup.py install for docopt 10:17:01 INFO - Successfully installed blobuploader requests docopt 10:17:01 INFO - Cleaning up... 10:17:01 INFO - Return code: 0 10:17:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17:01 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')]} 10:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:01 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')]} 10:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:01 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 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:17:01 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 10:17:01 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 10:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:01 INFO - 'HOME': '/Users/cltbld', 10:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:01 INFO - 'LOGNAME': 'cltbld', 10:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:01 INFO - 'MOZ_NO_REMOTE': '1', 10:17:01 INFO - 'NO_EM_RESTART': '1', 10:17:01 INFO - 'PAGER': '/bin/cat', 10:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:01 INFO - 'PWD': '/builds/slave/test', 10:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:01 INFO - 'SHELL': '/bin/bash', 10:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:17:01 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:01 INFO - 'USER': 'cltbld', 10:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:01 INFO - 'XPC_FLAGS': '0x0', 10:17:01 INFO - 'XPC_SERVICE_NAME': '0', 10:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-VuiODw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Qqnf9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-6saYLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-AJCcNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-T2lEI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-CPNKw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-9lL2x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Z2h7Ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-vPpuK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:17:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-cQ1mOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-4f_0wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-8ftZYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FTEbcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qDFxl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ypEdd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-6fqoT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:17:03 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-CgXNQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:17:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:17:03 INFO - Running setup.py install for manifestparser 10:17:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:17:03 INFO - Running setup.py install for mozcrash 10:17:04 INFO - Running setup.py install for mozdebug 10:17:04 INFO - Running setup.py install for mozdevice 10:17:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Running setup.py install for mozfile 10:17:04 INFO - Running setup.py install for mozhttpd 10:17:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Running setup.py install for mozinfo 10:17:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Running setup.py install for mozInstall 10:17:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:17:04 INFO - Running setup.py install for mozleak 10:17:05 INFO - Running setup.py install for mozlog 10:17:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:17:05 INFO - Running setup.py install for moznetwork 10:17:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:17:05 INFO - Running setup.py install for mozprocess 10:17:05 INFO - Running setup.py install for mozprofile 10:17:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:17:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:17:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:17:05 INFO - Running setup.py install for mozrunner 10:17:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:17:05 INFO - Running setup.py install for mozscreenshot 10:17:06 INFO - Running setup.py install for moztest 10:17:06 INFO - Running setup.py install for mozversion 10:17:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:17:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:17:06 INFO - Cleaning up... 10:17:06 INFO - Return code: 0 10:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17: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')]} 10:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17: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')]} 10:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:06 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 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:17:06 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 10:17:06 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 10:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:06 INFO - 'HOME': '/Users/cltbld', 10:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:06 INFO - 'LOGNAME': 'cltbld', 10:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:06 INFO - 'MOZ_NO_REMOTE': '1', 10:17:06 INFO - 'NO_EM_RESTART': '1', 10:17:06 INFO - 'PAGER': '/bin/cat', 10:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:06 INFO - 'PWD': '/builds/slave/test', 10:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:06 INFO - 'SHELL': '/bin/bash', 10:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:17:06 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:06 INFO - 'USER': 'cltbld', 10:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:06 INFO - 'XPC_FLAGS': '0x0', 10:17:06 INFO - 'XPC_SERVICE_NAME': '0', 10:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:17:06 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-l92Fu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:17:06 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)) 10:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:17:06 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-J5MGzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:17:06 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)) 10:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:17:06 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-TI5001-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:17:06 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)) 10:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:17:06 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-QoIVt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-wnwwSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-F90ObV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UAysyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ZEV6oI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-u8Jqge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-IPmWXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ysq2Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-pRoMyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:17:07 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)) 10:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:17:07 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-h_MfTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:17:08 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)) 10:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:17:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qWy0mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:17:08 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)) 10:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Ut3ZRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:08 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)) 10:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:17:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-N3vrl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:17:08 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)) 10:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:17:08 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-e1iXhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:17:08 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)) 10:17:08 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)) 10:17:08 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)) 10:17:08 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)) 10:17:08 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)) 10:17:08 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)) 10:17:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:17: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 10:17: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 10:17: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 10:17: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 10:17: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 10:17: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 10:17:10 INFO - Downloading blessings-1.6.tar.gz 10:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:17:10 INFO - Installing collected packages: blessings 10:17:10 INFO - Running setup.py install for blessings 10:17:11 INFO - Successfully installed blessings 10:17:11 INFO - Cleaning up... 10:17:11 INFO - Return code: 0 10:17:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:17:11 INFO - Reading from file tmpfile_stdout 10:17:11 INFO - Current package versions: 10:17:11 INFO - blessings == 1.6 10:17:11 INFO - blobuploader == 1.2.4 10:17:11 INFO - docopt == 0.6.1 10:17:11 INFO - manifestparser == 1.1 10:17:11 INFO - mozInstall == 1.12 10:17:11 INFO - mozcrash == 0.17 10:17:11 INFO - mozdebug == 0.1 10:17:11 INFO - mozdevice == 0.48 10:17:11 INFO - mozfile == 1.2 10:17:11 INFO - mozhttpd == 0.7 10:17:11 INFO - mozinfo == 0.9 10:17:11 INFO - mozleak == 0.1 10:17:11 INFO - mozlog == 3.2 10:17:11 INFO - moznetwork == 0.27 10:17:11 INFO - mozprocess == 0.22 10:17:11 INFO - mozprofile == 0.28 10:17:11 INFO - mozrunner == 6.11 10:17:11 INFO - mozscreenshot == 0.1 10:17:11 INFO - mozsystemmonitor == 0.3 10:17:11 INFO - moztest == 0.7 10:17:11 INFO - mozversion == 1.4 10:17:11 INFO - psutil == 3.1.1 10:17:11 INFO - requests == 1.2.3 10:17:11 INFO - wsgiref == 0.1.2 10:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:11 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 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:17:11 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 10:17:11 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 10:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:11 INFO - 'HOME': '/Users/cltbld', 10:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:11 INFO - 'LOGNAME': 'cltbld', 10:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:11 INFO - 'MOZ_NO_REMOTE': '1', 10:17:11 INFO - 'NO_EM_RESTART': '1', 10:17:11 INFO - 'PAGER': '/bin/cat', 10:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:11 INFO - 'PWD': '/builds/slave/test', 10:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:11 INFO - 'SHELL': '/bin/bash', 10:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:17:11 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:11 INFO - 'USER': 'cltbld', 10:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:11 INFO - 'XPC_FLAGS': '0x0', 10:17:11 INFO - 'XPC_SERVICE_NAME': '0', 10:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 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)) 10:17:11 INFO - Cleaning up... 10:17:11 INFO - Return code: 0 10:17:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:11 INFO - 'HOME': '/Users/cltbld', 10:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:11 INFO - 'LOGNAME': 'cltbld', 10:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:11 INFO - 'MOZ_NO_REMOTE': '1', 10:17:11 INFO - 'NO_EM_RESTART': '1', 10:17:11 INFO - 'PAGER': '/bin/cat', 10:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:11 INFO - 'PWD': '/builds/slave/test', 10:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:11 INFO - 'SHELL': '/bin/bash', 10:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:17:11 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:11 INFO - 'USER': 'cltbld', 10:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:11 INFO - 'XPC_FLAGS': '0x0', 10:17:11 INFO - 'XPC_SERVICE_NAME': '0', 10:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:12 INFO - Downloading/unpacking jsonschema 10:17: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 10:17: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 10:17: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 10:17: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 10:17: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 10:17: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 10:17:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:17:14 INFO - Downloading/unpacking functools32 (from jsonschema) 10:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:14 INFO - Downloading functools32-3.2.3-2.tar.gz 10:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:17:15 INFO - warning: no files found matching '*.txt' 10:17:15 INFO - no previously-included directories found matching 'build' 10:17:15 INFO - no previously-included directories found matching 'dist' 10:17:15 INFO - no previously-included directories found matching '.git*' 10:17:15 INFO - Installing collected packages: jsonschema, functools32 10:17:15 INFO - Running setup.py install for functools32 10:17:15 INFO - warning: no files found matching '*.txt' 10:17:15 INFO - no previously-included directories found matching 'build' 10:17:15 INFO - no previously-included directories found matching 'dist' 10:17:15 INFO - no previously-included directories found matching '.git*' 10:17:15 INFO - Successfully installed jsonschema functools32 10:17:15 INFO - Cleaning up... 10:17:15 INFO - Return code: 0 10:17:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ba5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3d3cab990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, '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.5QgYsJhY0L/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.svK5jVQkUC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 10:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:15 INFO - 'HOME': '/Users/cltbld', 10:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:15 INFO - 'LOGNAME': 'cltbld', 10:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:15 INFO - 'MOZ_NO_REMOTE': '1', 10:17:15 INFO - 'NO_EM_RESTART': '1', 10:17:15 INFO - 'PAGER': '/bin/cat', 10:17:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:15 INFO - 'PWD': '/builds/slave/test', 10:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:15 INFO - 'SHELL': '/bin/bash', 10:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:17:15 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:15 INFO - 'USER': 'cltbld', 10:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:15 INFO - 'XPC_FLAGS': '0x0', 10:17:15 INFO - 'XPC_SERVICE_NAME': '0', 10:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:15 INFO - Downloading/unpacking flake8 10:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:17:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:17:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:17:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:17:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:17:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:17:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:17:19 INFO - no previously-included directories found matching 'docs/_build' 10:17:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:17:19 INFO - Running setup.py install for pep8 10:17:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:17:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:17:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:17:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:17:19 INFO - no previously-included directories found matching 'docs/_build' 10:17:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:17:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:17:19 INFO - Cleaning up... 10:17:19 INFO - Return code: 0 10:17:19 INFO - Running post-action listener: _resource_record_post_action 10:17:19 INFO - Running post-action listener: _start_resource_monitoring 10:17:19 INFO - Starting resource monitoring. 10:17:19 INFO - ##### 10:17:19 INFO - ##### Finished create-virtualenv step (success) 10:17:19 INFO - ##### 10:17:19 INFO - ##### 10:17:19 INFO - ##### Running install step. 10:17:19 INFO - ##### 10:17:19 INFO - Running pre-action listener: _resource_record_pre_action 10:17:19 INFO - Running main action method: install 10:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:17:19 INFO - Reading from file tmpfile_stdout 10:17:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:17:20 INFO - Reading from file tmpfile_stdout 10:17:20 INFO - Output received: 10:17:20 INFO - Usage: mozinstall [options] installer 10:17:20 INFO - Options: 10:17:20 INFO - -h, --help show this help message and exit 10:17:20 INFO - -d DEST, --destination=DEST 10:17:20 INFO - Directory to install application into. [default: 10:17:20 INFO - "/builds/slave/test"] 10:17:20 INFO - --app=APP Application being installed. [default: firefox] 10:17:20 INFO - mkdir: /builds/slave/test/build/application 10:17:20 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'] 10:17:20 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 10:17:54 INFO - Reading from file tmpfile_stdout 10:17:54 INFO - Output received: 10:17:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:17:54 INFO - Running post-action listener: _resource_record_post_action 10:17:54 INFO - ##### 10:17:54 INFO - ##### Finished install step (success) 10:17:54 INFO - ##### 10:17:54 INFO - ##### 10:17:54 INFO - ##### Running run-tests step. 10:17:54 INFO - ##### 10:17:54 INFO - Running pre-action listener: _resource_record_pre_action 10:17:54 INFO - Running main action method: run_tests 10:17:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:17:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:17:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:17:54 INFO - 2016-06-02 10:17:54.111 screenresolution[1642:7421] starting screenresolution argv=screenresolution get 10:17:54 INFO - 2016-06-02 10:17:54.113 screenresolution[1642:7421] Display 0: 1600x1200x32@60 10:17:54 INFO - 2016-06-02 10:17:54.119 screenresolution[1643:7423] starting screenresolution argv=screenresolution list 10:17:54 INFO - Available Modes on Display 0 10:17:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:17:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:17:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:17:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:17:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:17:54 INFO - Intel Iris: 10:17:54 INFO - Chipset Model: Intel Iris 10:17:54 INFO - Type: GPU 10:17:54 INFO - Bus: Built-In 10:17:54 INFO - VRAM (Dynamic, Max): 1536 MB 10:17:54 INFO - Vendor: Intel (0x8086) 10:17:54 INFO - Device ID: 0x0a2e 10:17:54 INFO - Revision ID: 0x0009 10:17:54 INFO - Displays: 10:17:54 INFO - Display: 10:17:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:17:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:17:54 INFO - Display Serial Number: 1600x1200 60 10:17:54 INFO - Main Display: Yes 10:17:54 INFO - Mirror: Off 10:17:54 INFO - Online: Yes 10:17:54 INFO - Rotation: Supported 10:17:54 INFO - Return code: 0 10:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:17:54 INFO - Python 2.7.3 10:17:54 INFO - Return code: 0 10:17:54 INFO - grabbing minidump binary from tooltool 10:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:54 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 0x105a49d30>, '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 0x10586ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a40b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:17:54 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 10:17:54 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 10:17:54 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 10:17:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:17:54 INFO - Return code: 0 10:17:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:17:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:17:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:17:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:17:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:17:55 INFO - Return code: 0 10:17:55 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/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 10:17:55 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/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 10:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5QgYsJhY0L/Render', 10:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:55 INFO - 'HOME': '/Users/cltbld', 10:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:55 INFO - 'LOGNAME': 'cltbld', 10:17:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:17:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:55 INFO - 'MOZ_NO_REMOTE': '1', 10:17:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:17:55 INFO - 'NO_EM_RESTART': '1', 10:17:55 INFO - 'PAGER': '/bin/cat', 10:17:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:55 INFO - 'PWD': '/builds/slave/test', 10:17:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:55 INFO - 'SHELL': '/bin/bash', 10:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.svK5jVQkUC/Listeners', 10:17:55 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 10:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:55 INFO - 'USER': 'cltbld', 10:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:55 INFO - 'XPC_FLAGS': '0x0', 10:17:55 INFO - 'XPC_SERVICE_NAME': '0', 10:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:55 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/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 10:17:55 INFO - mozversion application_buildid: 20160602084252 10:17:55 INFO - mozversion application_changeset: 0979bb255f40483c20acd85843db49a4694f72f6 10:17:55 INFO - mozversion application_display_name: Nightly 10:17:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:17:55 INFO - mozversion application_name: Firefox 10:17:55 INFO - mozversion application_remotingname: firefox 10:17:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:17:55 INFO - mozversion application_vendor: Mozilla 10:17:55 INFO - mozversion application_version: 49.0a1 10:17:55 INFO - mozversion platform_buildid: 20160602084252 10:17:55 INFO - mozversion platform_changeset: 0979bb255f40483c20acd85843db49a4694f72f6 10:17:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:17:55 INFO - mozversion platform_version: 49.0a1 10:17:55 INFO - using testdate: 1464887875 10:17:55 INFO - actual date: 1464887875 10:17:55 INFO - starting webserver on 'localhost:49270' 10:17:55 INFO - SUITE-START | Running 2 tests 10:17:55 INFO - TEST-START | dromaeo_css 10:17:55 INFO - operating with platform_type : mac_ 10:17:55 INFO - Initialising browser for dromaeo_css test... 10:17:55 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpD1VRUb/profile http://localhost:49270/getInfo.html) 10:17:58 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 10:17:58 INFO - PROCESS | 1661 | colorDepth:24 10:17:58 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 10:17:58 INFO - PROCESS | 1661 | __metrics 10:17:58 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:17:58 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:17:59 INFO - TEST-INFO | 1661: exit 0 10:17:59 INFO - Browser initialized. 10:17:59 INFO - Running cycle 1/1 for dromaeo_css test... 10:17:59 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpD1VRUb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:11 INFO - PROCESS | 1663 | RSS: Main: 242151424 10:18:11 INFO - PROCESS | 1663 | 10:19:01 INFO - PROCESS | 1663 | 1464887941993 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] 10:21:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 10:21:14 INFO - PROCESS | 1663 | RSS: Main: 380198912 10:21:14 INFO - PROCESS | 1663 | 10:24:17 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 10:24:18 INFO - PROCESS | 1663 | RSS: Main: 377999360 10:24:18 INFO - PROCESS | 1663 | 10:27:20 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 10:27:20 INFO - PROCESS | 1663 | RSS: Main: 375349248 10:27:20 INFO - PROCESS | 1663 | 10:30:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 10:30:23 INFO - PROCESS | 1663 | RSS: Main: 372924416 10:30:23 INFO - PROCESS | 1663 | 10:33:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 10:33:27 INFO - PROCESS | 1663 | RSS: Main: 336535552 10:33:27 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 10:36:31 INFO - PROCESS | 1663 | RSS: Main: 379441152 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | __start_tp_report 10:36:31 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 10:36:31 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 10:36:31 INFO - PROCESS | 1663 | |i|pagename|runs| 10:36:31 INFO - PROCESS | 1663 | |0;dojo.html;2894.797364184507;3472.392331342016;3484.878029268976;3499.7375196860244;3539.2213712983153;5576.776928922838;5641.943580564198;5653.689047102408;5690.658560486374;5715.628484148533;4757.809687612491;5655.4061823508555;5670.744816483248;5792.4497172768515;5821.27234095738;5062.038212739586;5089.643724939256;5142;5155.510226528492;5257.8948421031555;4417.668674849399;5292.444293349201;5293.550048245885;5320.813771517997;5359.705216213107;5101.183810590288;5139.12634852076;5166.9224961625605;5200.01199772044;5208.932168905365;5152.098382782998;5197.610179236573;5200.557952574036;5203.323567936182;5207.895842083175;5129.717865517395;5133.153029750087;5174.689518628857;5212.374515058179;5248;5622.409646987048;5676.687782171979;5715.942840571583;5719.48524632785;5725.312962444492;6059.099586382425;6256.280527739323;6264.530160237955;6271.184745983039;6278.717457714368;6214.067889816519;6214.720337584819;6253.374662533755;6257.468115210213;6257.968710156466;5808.564357673192;5850.0932355484965;5853.5902486825935;5868.149118377854;5874.295084589791;6213.875722485525;6214.5960512566535;6277.2153480814895;6322.589031712924;6336.112944187817;6138.202033735586;6164.198654174928;6176.69116544171;6177.876442471125;6179.567430279837;6254.6872656366995;6264.498840092781;6268.122462855204;6275.121482992385;6283.3088360280335;6078.817635470943;6106.1146133811235;6213.627182369072;6223.844403889939;6232.875342493125;6121.696863452088;6138.120194445427;6191.628502289877;6215.875682486325;6251;4974.452810190876;4975.601951843843;4977.776000080005;4983.651144419856;5006.173981293119;4416.6025057744955;4481.081378317437;4499.775011249424;4505.459344878635;4516.570925762047;5622.746976386073;5674.858128546737;5712.428757124254;5742.368339482654;5747.281589801275;6268.1851359323;6268.561200715997;6296.685165741695;6296.937030629727;6303.054541818764;5547.611667183339;5643.238162848045;5699.401562835926;5722.62802917809;5723.54211663066;5367.5169234769055;5372.758225879845;5432.565394768409;5445.37378201504;5445.428230035789;6338.968305158444;6348.079528468346;6394.488440924808;6397;6415.037744338293;6246.781362652286;6257.155284036597;6269.31037585856;6285.59143655661;6286.905696414466;6226.25284965807;6303.590266632654;6348.587341822767;6384.201974753156;6390.041493775971;6147;6202.596831205957;6208.658523781235;6209.720562574605;6214.850252703264;6240.063990401476;6244.937550624436;6263;6269.68651567429;6274.780382686584;5968.850778730392;6035.305939816977;6054.969725151346;6082.391760823883;6128.785492507741;5925.496332811586;6043.214382130295;6045.274567051806;6100.206973093469;6103.877922441615;5964.7912323068485;5965.731542080704;5975.551833612583;5997.490213331916;6042.1540984261355;5954.285485741739;6024.759009639565;6096.458138686707;6103.450689437972;6117.084826762104;5947.078202878561;6029.427204415573;6032.75868965254;6035.6076855004285;6088.756449742138 10:36:31 INFO - PROCESS | 1663 | |1;ext.html;505.77097652703924;507.8831868670206;514.6191818054641;525.9526642602167;549.5466240351711;30656.69343306569;32027;32114.678853211462;32126;32199;32706;43392.78303608477;44686.32970505445;45103.548964510344;47648.28527572089;9417.43495390277;9590.9520452398;9601.951990240039;9605.567749451257;9616;8532.658693652247;9445.433274003548;9575.377600455948;9598.90401095987;9627.277954153431;8626.870596941051;11487.59793407231;11679.416029198506;11720.413979301044;11967.922886940174;11524.962753352196;11694.824577631258;11698.298102113815;11719.589814356457;11752.23610465317;8452.112528184574;11446.885531144666;11490.253133546294;11591.130665200059;11821.822672659917;41392.58607413917;41649;41886;41943.1611367774;41998.58001420008;3504.929901401983;3823.445600387928;4206.894827629303;4241;4365.105153443536;31589.8420507896;31868.379085032542;32741.761318188976;32868.178295542566;33130;3164.5886034815326;3298.224917144469;3312.337532493511;3329.7003269705842;3334.049795808183;454964.9999999967;457534;460649;460817;461992;65396;65427.67286163539;65713.34286657168;65777.01334479987;65798.34201658018;69364.6531767348;69463;69468.65265673639;69510.95733564;69609.65195173991;16284.348626055062;16934.40729574459;16938.49184524466;16957.576060598585;16971.91514042422;12971.41628626714;13040.608781736562;13045.804312935314;13066.738665226832;13093.934530327479;15790.605234869221;16049.759253611206;18150.636987260183;18439;18705.90647046783;5980.910286345708;6153.476954458832;6167.876642467126;7418.295261950106;7456.7762967111075;903.3270213690843;948.9952550237205;1017.6743442098606;1065.4512925843196;1080.5353697909818;26830.731692682824;29031;31020.844895775375;31084.689153108633;31281.48040913953;1054.8206804843196;1084.3385534823751;1162.5291756838494;1162.773259214462;1236.5053978408528;12525.93737031309;15215.923920380328;15808.68382632341;15970;16414.589635258973;176063;177356;177727;177963;178340.10829945767;62556.68721656453;62716;62725;62833;62885.37114628887;1374.0793668242302;1424.0601203205833;1443.364919435445;1443.9278036098153;1446.1250943179646;30272.243193920327;30332.69667303387;30515.694843051286;30899.382012360078;31113.066608001795;54031.459685402646;54241.18638220351;54559.454405455435;54585;54712.726436367564;502743;586362;587493;588453;589616;26285.4742905137;26298.86850565735;26585.335366615214;28476;42774.57225427706;35429.1142721434;41054.178916422105;48724.26913596228;50858.49141508686;52422.427327180245;40295.59704403036;41197.382039270036;41251.76244712663;41914;42132.78933605435;39571;40134.397984030846;40189.79905100573;40518;41147.7942610285;38887.41668875026;39524.40713389244;39853.20293594054;39883;39905;33510.497342540366;35075.82462087776;35335.469967951016;35753.927382178575;35780.105497362776;21359.25242616501;25546.489070218864;25985.610215847155;26001.699915004552;26479 10:36:31 INFO - PROCESS | 1663 | |2;jquery.html;340.8224584062066;358.25840510143996;376.206204907645;380.0858934263097;385.33529661334194;20029.499262518428;20693.689594656054;21046.684299735498;21124.788752112476;21535.784642153554;20307.08618112185;20634.1746330147;20908.58182836346;21131.943402829835;21230.787692123075;5165.664231824938;5839.854003649911;6079.088136779475;6096.664683442431;6108;5895.9705201473935;6016.578839481238;6036.275646922375;6052.697365131748;6056.666883321415;5750.683712395817;5933.706516207767;5954.047352423613;5959.195508606326;5961.254843144607;5737.540996720252;5900.911486327708;5913.172155898178;5916.526677865761;5916.733746981375;5069.138246498067;5180.197069454203;5203;5205.375354957391;5207.843764687065;24073.398165045837;25045.49909001828;25670.358241043938;25772;25981.960721571115;2095.528506086134;2221.922232721863;2826;2960.792744507864;3089.8609562569513;9669;10079.69760907174;10480.528376223088;10483.056524912718;11147.498362573704;1785.8392744652913;1826.5981484073482;1858.219547789918;1881.9905900470546;1895.0240626077582;69998;115407.42296288548;119923.4003829984;133302.3334883329;135180;9188.816223675623;11690.298582084932;11758.35329056893;11895.821562676567;11907.464164112462;12949.158304710163;13550.796738048937;13717.588472345846;14525.927370363292;14632.268386580628;7695.538267703956;7870.4652592745015;8619.138086191457;8676.826463470696;8707.172818582225;8574.185452382013;8578.828423431622;8627.415721357289;8679.392442529088;8695.000149965543;4464.06254686513;4538.591526762541;4541.909161816745;4588.655850811202;4629.328747331618;1885.2459016393552;1915.9042047897549;1930.845532357408;1935.7096435534781;1974.2497850816599;773.4894969320334;936.5644975086617;960.2701946520694;1031.1029404418055;1052.9368237905596;15171.696566068616;19301.324453644054;20674.896625516776;20957.47606309824;21227.78772212289;809.874469457235;965.5654955270016;965.9178969787647;972.9659461918799;1067.7597540553313;10244.64143754965;10947.069499092666;10975.615853445091;10995;11020.393878336608;540014;596959;606860;609278;610403;126609;131161;135039.32480337535;135716;136458.6354136446;989.3766926836047;990.841465365538;1011.1758916483043;1012.372329155928;1012.767063575367;16333.67332653364;16411;17194.882332648336;17896.10519474047;18274.269029238683;51374.972500550524;51389.486105139964;51560.484395157066;52995.47004529905;53373.7331313341;76898.46203076019;77632;78003;78373.97891278623;78626.6068669653;7756.185600512091;7853.253940875607;7920.564368959763;7941.563214023284;8120.431569790058;8872.29021678277;11721.413929303671;11917.404129793302;12032.639020829389;12263.75472490563;10403.42781147014;10424.635137770141;10584.4707764613;10634.787304253718;10667.306625069346;10125.341852779546;10455.58177672915;10475.266731328733;10514.474276286002;10591.576336946744;10565;10629.734256643645;10789.946050269698;10818.729531761768;10825.621103261348;6017.398260173947;7831.960840195762;7909.644066017158;7928.816316753353;8033.3171680405485;7894.210578942289;9424.670136545188;10486.737831554272;10549.683509494727;10685.412302323446 10:36:31 INFO - PROCESS | 1663 | |3;mootools.html;1386.6048176269765;1537.9077255364675;1568.6705791783725;1783.9197236124373;1923.7595300587418;7714.49855759376;8420.536870472122;8631.48211107334;8632.784180395493;8750.737477875675;8419.621117049732;8610.440321379107;8615.43999640023;8618;8620.094890036535;2344.66002429648;2378.940526486834;2379.274321331993;2379.333786539763;2382.0233704181287;2328.1851352026824;2339.9181028664;2369.2063158841725;2370.7510711375317;2372.1815973489206;2323.9651405228933;2345.8240631952585;2358.9646155307682;2362.7400985891536;2364.6216605343147;2022.656148454759;2267.2744721688982;2306.077568972408;2331.1491305673394;2353.364591560286;2311.2719493359537;2342.730585982617;2347.917822876208;2349.4361353275262;2352.282553821084;8501.23488885997;8526.531040792754;8604.483730976099;8625.65497380104;8720.171583699603;5195.610329225286;5343;5343.198520221959;5348.732563371816;5351.812686555952;3463.1169051891857;3495.7028652564563;3555.8933232002823;3557.839897204632;3611.5846677632144;1054.7995880782628;1069.903708666216;1077.9083777878889;1084.0514549768952;1096.1723898456614;11325;11439.256448330832;11445;11445.599404020819;11455.427228638535;4459.818148190732;4460;4580.221362368406;4594.701344412602;4598.195315819718;7659.731909383145;9038.593263303168;9039.593218305192;9043.864342034874;9062.81874362509;5086.18621020635;5164.25118357836;5180.067587834226;5181.4300426953005;5182.041322355376;4331.6534677225745;4487.618552423015;4653.371794807657;4815.374001379798;4876.780544875489;3150.763692723055;3750.8124593770203;3823.9044023899623;3842.4620553122586;3851.7111216658864;978.3542861711236;1156.9537218511166;1162.5640384855678;1172.8709841917382;1174.518447436547;661.3320546248227;806.2783808491367;878.3456325037888;888.5246393179747;990.2375171118199;5876.471117599436;5995.640261584319;6005.3394126646035;6039.1545183674325;6179.073139029181;906.582971832951;908.9409188402733;910.2854259406466;915.706973768401;929.5677509957902;3885.3589157788647;3983.0440694233753;3998.1803730235233;4000.6599439047427;4056.452378928807;8513.872291915626;10529.36823790575;10650.094741947021;10653.84019239712;10653.893461065445;10553.155747540179;10635.149188064936;10684.412357320422;10688.893111068945;10695.037446629685;7759.107702614158;8009.959950200212;8112.269895709533;8177.059638141571;8180.059293181242;5048.444671086251;5919.378465261086;5931.347551769216;6030.48740857032;6076.665783381956;9184.173424391836;9590.232781377612;9839.655612053544;9844.60621575129;9918.603255869974;9778.462184579916;9999;10008.44953527563;10122.190224781853;10171.64399246023;7425.628718564159;7469.7385591504035;7536.811579710551;7558.168601453725;7593.734219302298;8073.15231900642;8147.511149331059;8149.755507334789;8152.3478121751305;8158.1026087129185;4878.170710979071;7044.295570442915;7288.453365997465;7426.5915374654915;7541.5475071495885;7232.74685386009;7329.596872172082;7332.340089391981;7435.8141046474275;7442.851142977219;6909.2017732818285;6938.098047253825;7026.789196324118;7050.858982820418;7056.259092795388;4943.950560494349;4953.900921981468;5001.174806156943;5024.547790698854;5091.6181286402925;4966;4985.1276026695905;5023.974880125576;5024.120778863758;5122.820701275437 10:36:31 INFO - PROCESS | 1663 | |4;prototype.html;303.28727490397563;313.1310613048789;318.4157071773295;320.0175461333707;322.87246537617625;5166.457521960192;5323.22813192087;5366.543843773284;5374.6237763356585;5407.351117865852;4596.586307232348;5266.394364648067;5334.9987509367975;5355.277037599923;5361.624686271963;3129.499280115182;3422.117835190648;3777.7166712496605;3939.704522160835;4035.4148648445957;3401.5203386526814;3965.583613720561;4018.7990600470007;4035.3341698619693;4105.3431450967855;3247.4249988755555;3969.2458432897656;4029.697772667047;4040.1717647882115;4047.6964227683043;3423.7674437202586;3813.9427908581392;3924.2151569686175;3944.5799512175736;4036.636702696742;2945.337299107705;3085.490894002487;3525.7179425645886;3527.9294414111832;3550.378683730337;3960.564337922855;4027.1140349123148;4883.633727470454;5023.4474207837475;5084.567811736007;3650.9269814603826;3924.8626298079716;4479.910401791978;4628;4695.88260293492;5072.315237442935;5507.394186639466;5508.256385387961;5535.557155427597;5546.4453554644615;3475.2701932594186;3511.8568880851494;3886.24218277436;3918.235943990923;3923.0780766519847;5198.714070726109;5477.589180811455;5558.861028474321;5704.71476426177;5829.009068458372;3207;3230.9838450807597;3337.6996070353784;3656.9760467069264;3848.7690738555775;3317.6350601433824;3922.2351641429645;3936.547297060875;3944.3097457944746;3949.980250098731;4323.265044942367;4574.078886867524;4575.267957126844;4593.586577208067;4611.515790841981;5238.449962753856;5323.068463018956;5470.562355011669;5608.663480191201;5609.579281553818;1124.0501775999267;1237.9814302785464;1267.2776517385003;1296.682312833362;1309.6594885329882;781.4803155901262;804.3806269172704;805.778410936997;810.9133760760491;817.7791996161003;4172.895677608023;4340.435743353407;4652.953470465252;4667.789949452283;5117.232415137684;5394.599403265509;5546.473085056994;5751.8562035949435;5802.82591522255;5835.533157347402;4160.313548264502;4535.324158746355;4815.518448155157;4955.702657840541;5228.607854410934;2729.426820367745;2811.4236581500745;2861.697927443008;3011.0040509692994;3021.7884748067863;5160.329157209539;5505.752241149158;5811.663317437014;5987.012987012987;6020.367861374581;4499.617532509707;5414.945850541523;5818.592698511148;5936.762529498858;5947.910781338283;5636.815199412319;5932.3474417814;5974.9103763444455;5988.461038506555;6000.309964354155;4499.910001800011;4991.750412479434;5025.623078269217;5091.821786237464;5768.730879206568;5237.921431178459;5429.891402172014;5532.861678458071;5680.488756011979;5718.085106382959;4879.414470263591;5499.037668407933;5556.832835194358;5937.910931335947;6015.789447369321;3917.451556781996;3918.1184233547224;3943.566207717206;3945.585713500041;3973.264945985002;5148.665336753099;5157.690538567698;5324.36107667067;5481.972590137183;5653.40639232891;4670.1126785910565;4936.802527898987;5002.599792016557;5521.944780552304;5568.387477377404;5276.443449182577;5351.628488827447;5459.508644222039;5459.590530710292;5520.889582208413;4857.902841943071;4984.00319936007;5362;5390.946090539202;5400.378956419905;4516.8193272268745;5258.8948221036135;5261.579373569039;5444.863878403072;5501.514591060311;4910.74775932135;5297.337832770903;5360.65155764874;5370.516653501204;5402.135658294654 10:36:31 INFO - PROCESS | 1663 | |5;yui.html;709.6026225313824;711.5695004522264;736.4108713029576;741.9777406677798;759.8176437654964;15418.61453463664;15446.304916278767;15710.978786378877;15717.842821571781;15718.135502547355;12480.375981200948;15865.52403427899;15911.045337279771;16036;16044.197790110476;699.965001749913;876.8071024374659;879.2174964281794;887.3655336434448;887.4497811356968;717.4583189691775;869.3436455476107;900.5092224737534;900.5182227508284;904.2404380320529;942.3215284994827;942.6229508196708;949.3070058857038;950.2872845365976;952.4999375327939;732.3116270705519;913.4610579757908;943.8395472769646;944.924406047518;945.4327403557879;689.2108535726616;690.7582346178797;692.2800287700785;693.0643631097977;698.1447726535005;15702.607434814106;15788.447404340908;15795.526134215877;15798.842011579853;15804.288807003712;11841.467133978991;13558.593242202749;13575.253361065137;13816.585502434944;13953.581392558139;11097.55609775608;12999.484147454456;13223.537176198786;13460.259685717281;13798.241096739675;4519.254323036695;4595.356650068993;4654.25531914892;4716.153743242645;4717.00942802012;84946;112871;115087.42456287748;115594;115643.843561565;10246;11201.887981120248;11514.30914145154;11810.822837657443;11960.983316418027;22125.66811497829;22570.774292257196;22571;22576.20983265578;22581.887090564443;7199.424046076301;8293.751187464386;8308.584570771438;8328.500289982621;8500.574971251413;3946.3291240489184;4445.555444455593;4501.752403617833;4546.727196368229;4682.157211701859;3587.3901436755814;3666.211764470652;3707.796071216109;3709.1283548366114;3709.3694072007825;656.5699466849221;668.5654324688894;675.392147067643;679.49038221334;716.8494616130566;558.5392051557482;573.0830670926484;593.7981086430634;694.8054544727485;722.8391460411995;9512.714618561455;10300.330478519023;10627.84058239127;10739.570417183382;10746.570137194425;611.9696098073827;632.7152781248365;675.7128220506363;683.2859661653584;698.5599072584341;4508.616767574793;4547.727136371828;4602.332661764092;4620.768961551909;4648.279516674921;92917.07082929219;122767.77232227742;125420.37289813493;139982.30008849892;144769;95882;108669;124969.75030249581;126975;128630.71369286375;100259.49870250603;107094.85810283906;108766;109732.80534389267;109741.35387969187;8979.14698103679;8993.865092023494;9013.729588112366;9107.58832380982;9158.95420522893;59225;60936.39063609307;61499.235084139815;62279;62492.37507624865;75905;87647.56176219079;91447;91944.54027729819;92175.53912230396;11911.344876031555;11944.98467630261;12061.733517980485;12154.331511766937;12209.755804884031;13150.671233218864;13225.801612976005;13310;13600.251986140462;13749.106308089942;10252.026057524523;10952.780944381228;12173.2696038238;12391.504339826666;12421.19262247951;12382.690432739253;12423.130380873252;12423.503059877865;12625.368731563569;12817;10024.298299118991;11995.40022998864;12072.3360215189;12196.51213951432;12498.37508124572;10091.798164036825;10211.540480678535;10327.793444131226;10715.946420267848;10816.4050977197;10219.693409197736;10391.896081039093;10637.042666160083;10666.413347265974;10849.566017359217 10:36:31 INFO - PROCESS | 1663 | __end_tp_report 10:36:31 INFO - PROCESS | 1663 | __start_cc_report 10:36:31 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1404 10:36:31 INFO - PROCESS | 1663 | __end_cc_report 10:36:31 INFO - PROCESS | 1663 | __startTimestamp1464888991329__endTimestamp 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | ------- Summary: start ------- 10:36:31 INFO - PROCESS | 1663 | Number of tests: 6 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5745.44 Median:5997.49 stddev:649.60 (10.8%) stddev-sans-first:611.89 10:36:31 INFO - PROCESS | 1663 | Values: 2894.8 3472.4 3484.9 3499.7 3539.2 5576.8 5641.9 5653.7 5690.7 5715.6 4757.8 5655.4 5670.7 5792.4 5821.3 5062.0 5089.6 5142.0 5155.5 5257.9 4417.7 5292.4 5293.6 5320.8 5359.7 5101.2 5139.1 5166.9 5200.0 5208.9 5152.1 5197.6 5200.6 5203.3 5207.9 5129.7 5133.2 5174.7 5212.4 5248.0 5622.4 5676.7 5715.9 5719.5 5725.3 6059.1 6256.3 6264.5 6271.2 6278.7 6214.1 6214.7 6253.4 6257.5 6258.0 5808.6 5850.1 5853.6 5868.1 5874.3 6213.9 6214.6 6277.2 6322.6 6336.1 6138.2 6164.2 6176.7 6177.9 6179.6 6254.7 6264.5 6268.1 6275.1 6283.3 6078.8 6106.1 6213.6 6223.8 6232.9 6121.7 6138.1 6191.6 6215.9 6251.0 4974.5 4975.6 4977.8 4983.7 5006.2 4416.6 4481.1 4499.8 4505.5 4516.6 5622.7 5674.9 5712.4 5742.4 5747.3 6268.2 6268.6 6296.7 6296.9 6303.1 5547.6 5643.2 5699.4 5722.6 5723.5 5367.5 5372.8 5432.6 5445.4 5445.4 6339.0 6348.1 6394.5 6397.0 6415.0 6246.8 6257.2 6269.3 6285.6 6286.9 6226.3 6303.6 6348.6 6384.2 6390.0 6147.0 6202.6 6208.7 6209.7 6214.9 6240.1 6244.9 6263.0 6269.7 6274.8 5968.9 6035.3 6055.0 6082.4 6128.8 5925.5 6043.2 6045.3 6100.2 6103.9 5964.8 5965.7 5975.6 5997.5 6042.2 5954.3 6024.8 6096.5 6103.5 6117.1 5947.1 6029.4 6032.8 6035.6 6088.8 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:57208.73 Median:35591.52 stddev:116594.46 (327.6%) stddev-sans-first:116843.81 10:36:31 INFO - PROCESS | 1663 | Values: 505.8 507.9 514.6 526.0 549.5 30656.7 32027.0 32114.7 32126.0 32199.0 32706.0 43392.8 44686.3 45103.5 47648.3 9417.4 9591.0 9602.0 9605.6 9616.0 8532.7 9445.4 9575.4 9598.9 9627.3 8626.9 11487.6 11679.4 11720.4 11967.9 11525.0 11694.8 11698.3 11719.6 11752.2 8452.1 11446.9 11490.3 11591.1 11821.8 41392.6 41649.0 41886.0 41943.2 41998.6 3504.9 3823.4 4206.9 4241.0 4365.1 31589.8 31868.4 32741.8 32868.2 33130.0 3164.6 3298.2 3312.3 3329.7 3334.0 454965.0 457534.0 460649.0 460817.0 461992.0 65396.0 65427.7 65713.3 65777.0 65798.3 69364.7 69463.0 69468.7 69511.0 69609.7 16284.3 16934.4 16938.5 16957.6 16971.9 12971.4 13040.6 13045.8 13066.7 13093.9 15790.6 16049.8 18150.6 18439.0 18705.9 5980.9 6153.5 6167.9 7418.3 7456.8 903.3 949.0 1017.7 1065.5 1080.5 26830.7 29031.0 31020.8 31084.7 31281.5 1054.8 1084.3 1162.5 1162.8 1236.5 12525.9 15215.9 15808.7 15970.0 16414.6 176063.0 177356.0 177727.0 177963.0 178340.1 62556.7 62716.0 62725.0 62833.0 62885.4 1374.1 1424.1 1443.4 1443.9 1446.1 30272.2 30332.7 30515.7 30899.4 31113.1 54031.5 54241.2 54559.5 54585.0 54712.7 502743.0 586362.0 587493.0 588453.0 589616.0 26285.5 26298.9 26585.3 28476.0 42774.6 35429.1 41054.2 48724.3 50858.5 52422.4 40295.6 41197.4 41251.8 41914.0 42132.8 39571.0 40134.4 40189.8 40518.0 41147.8 38887.4 39524.4 39853.2 39883.0 39905.0 33510.5 35075.8 35335.5 35753.9 35780.1 21359.3 25546.5 25985.6 26001.7 26479.0 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34693.41 Median:25876.98 stddev:99253.31 (383.6%) stddev-sans-first:99498.23 10:36:31 INFO - PROCESS | 1663 | Values: 340.8 358.3 376.2 380.1 385.3 20029.5 20693.7 21046.7 21124.8 21535.8 20307.1 20634.2 20908.6 21131.9 21230.8 5165.7 5839.9 6079.1 6096.7 6108.0 5896.0 6016.6 6036.3 6052.7 6056.7 5750.7 5933.7 5954.0 5959.2 5961.3 5737.5 5900.9 5913.2 5916.5 5916.7 5069.1 5180.2 5203.0 5205.4 5207.8 24073.4 25045.5 25670.4 25772.0 25982.0 2095.5 2221.9 2826.0 2960.8 3089.9 9669.0 10079.7 10480.5 10483.1 11147.5 1785.8 1826.6 1858.2 1882.0 1895.0 69998.0 115407.4 119923.4 133302.3 135180.0 9188.8 11690.3 11758.4 11895.8 11907.5 12949.2 13550.8 13717.6 14525.9 14632.3 7695.5 7870.5 8619.1 8676.8 8707.2 8574.2 8578.8 8627.4 8679.4 8695.0 4464.1 4538.6 4541.9 4588.7 4629.3 1885.2 1915.9 1930.8 1935.7 1974.2 773.5 936.6 960.3 1031.1 1052.9 15171.7 19301.3 20674.9 20957.5 21227.8 809.9 965.6 965.9 973.0 1067.8 10244.6 10947.1 10975.6 10995.0 11020.4 540014.0 596959.0 606860.0 609278.0 610403.0 126609.0 131161.0 135039.3 135716.0 136458.6 989.4 990.8 1011.2 1012.4 1012.8 16333.7 16411.0 17194.9 17896.1 18274.3 51375.0 51389.5 51560.5 52995.5 53373.7 76898.5 77632.0 78003.0 78374.0 78626.6 7756.2 7853.3 7920.6 7941.6 8120.4 8872.3 11721.4 11917.4 12032.6 12263.8 10403.4 10424.6 10584.5 10634.8 10667.3 10125.3 10455.6 10475.3 10514.5 10591.6 10565.0 10629.7 10789.9 10818.7 10825.6 6017.4 7832.0 7909.6 7928.8 8033.3 7894.2 9424.7 10486.7 10549.7 10685.4 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5589.05 Median:5067.32 stddev:3107.22 (61.3%) stddev-sans-first:3099.89 10:36:31 INFO - PROCESS | 1663 | Values: 1386.6 1537.9 1568.7 1783.9 1923.8 7714.5 8420.5 8631.5 8632.8 8750.7 8419.6 8610.4 8615.4 8618.0 8620.1 2344.7 2378.9 2379.3 2379.3 2382.0 2328.2 2339.9 2369.2 2370.8 2372.2 2324.0 2345.8 2359.0 2362.7 2364.6 2022.7 2267.3 2306.1 2331.1 2353.4 2311.3 2342.7 2347.9 2349.4 2352.3 8501.2 8526.5 8604.5 8625.7 8720.2 5195.6 5343.0 5343.2 5348.7 5351.8 3463.1 3495.7 3555.9 3557.8 3611.6 1054.8 1069.9 1077.9 1084.1 1096.2 11325.0 11439.3 11445.0 11445.6 11455.4 4459.8 4460.0 4580.2 4594.7 4598.2 7659.7 9038.6 9039.6 9043.9 9062.8 5086.2 5164.3 5180.1 5181.4 5182.0 4331.7 4487.6 4653.4 4815.4 4876.8 3150.8 3750.8 3823.9 3842.5 3851.7 978.4 1157.0 1162.6 1172.9 1174.5 661.3 806.3 878.3 888.5 990.2 5876.5 5995.6 6005.3 6039.2 6179.1 906.6 908.9 910.3 915.7 929.6 3885.4 3983.0 3998.2 4000.7 4056.5 8513.9 10529.4 10650.1 10653.8 10653.9 10553.2 10635.1 10684.4 10688.9 10695.0 7759.1 8010.0 8112.3 8177.1 8180.1 5048.4 5919.4 5931.3 6030.5 6076.7 9184.2 9590.2 9839.7 9844.6 9918.6 9778.5 9999.0 10008.4 10122.2 10171.6 7425.6 7469.7 7536.8 7558.2 7593.7 8073.2 8147.5 8149.8 8152.3 8158.1 4878.2 7044.3 7288.5 7426.6 7541.5 7232.7 7329.6 7332.3 7435.8 7442.9 6909.2 6938.1 7026.8 7050.9 7056.3 4944.0 4953.9 5001.2 5024.5 5091.6 4966.0 4985.1 5024.0 5024.1 5122.8 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4417.17 Median:4987.88 stddev:1383.42 (27.7%) stddev-sans-first:1352.41 10:36:31 INFO - PROCESS | 1663 | Values: 303.3 313.1 318.4 320.0 322.9 5166.5 5323.2 5366.5 5374.6 5407.4 4596.6 5266.4 5335.0 5355.3 5361.6 3129.5 3422.1 3777.7 3939.7 4035.4 3401.5 3965.6 4018.8 4035.3 4105.3 3247.4 3969.2 4029.7 4040.2 4047.7 3423.8 3813.9 3924.2 3944.6 4036.6 2945.3 3085.5 3525.7 3527.9 3550.4 3960.6 4027.1 4883.6 5023.4 5084.6 3650.9 3924.9 4479.9 4628.0 4695.9 5072.3 5507.4 5508.3 5535.6 5546.4 3475.3 3511.9 3886.2 3918.2 3923.1 5198.7 5477.6 5558.9 5704.7 5829.0 3207.0 3231.0 3337.7 3657.0 3848.8 3317.6 3922.2 3936.5 3944.3 3950.0 4323.3 4574.1 4575.3 4593.6 4611.5 5238.4 5323.1 5470.6 5608.7 5609.6 1124.1 1238.0 1267.3 1296.7 1309.7 781.5 804.4 805.8 810.9 817.8 4172.9 4340.4 4653.0 4667.8 5117.2 5394.6 5546.5 5751.9 5802.8 5835.5 4160.3 4535.3 4815.5 4955.7 5228.6 2729.4 2811.4 2861.7 3011.0 3021.8 5160.3 5505.8 5811.7 5987.0 6020.4 4499.6 5414.9 5818.6 5936.8 5947.9 5636.8 5932.3 5974.9 5988.5 6000.3 4499.9 4991.8 5025.6 5091.8 5768.7 5237.9 5429.9 5532.9 5680.5 5718.1 4879.4 5499.0 5556.8 5937.9 6015.8 3917.5 3918.1 3943.6 3945.6 3973.3 5148.7 5157.7 5324.4 5482.0 5653.4 4670.1 4936.8 5002.6 5521.9 5568.4 5276.4 5351.6 5459.5 5459.6 5520.9 4857.9 4984.0 5362.0 5390.9 5400.4 4516.8 5258.9 5261.6 5444.9 5501.5 4910.7 5297.3 5360.7 5370.5 5402.1 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:23561.32 Median:13084.64 stddev:36569.98 (279.5%) stddev-sans-first:36632.32 10:36:31 INFO - PROCESS | 1663 | Values: 709.6 711.6 736.4 742.0 759.8 15418.6 15446.3 15711.0 15717.8 15718.1 12480.4 15865.5 15911.0 16036.0 16044.2 700.0 876.8 879.2 887.4 887.4 717.5 869.3 900.5 900.5 904.2 942.3 942.6 949.3 950.3 952.5 732.3 913.5 943.8 944.9 945.4 689.2 690.8 692.3 693.1 698.1 15702.6 15788.4 15795.5 15798.8 15804.3 11841.5 13558.6 13575.3 13816.6 13953.6 11097.6 12999.5 13223.5 13460.3 13798.2 4519.3 4595.4 4654.3 4716.2 4717.0 84946.0 112871.0 115087.4 115594.0 115643.8 10246.0 11201.9 11514.3 11810.8 11961.0 22125.7 22570.8 22571.0 22576.2 22581.9 7199.4 8293.8 8308.6 8328.5 8500.6 3946.3 4445.6 4501.8 4546.7 4682.2 3587.4 3666.2 3707.8 3709.1 3709.4 656.6 668.6 675.4 679.5 716.8 558.5 573.1 593.8 694.8 722.8 9512.7 10300.3 10627.8 10739.6 10746.6 612.0 632.7 675.7 683.3 698.6 4508.6 4547.7 4602.3 4620.8 4648.3 92917.1 122767.8 125420.4 139982.3 144769.0 95882.0 108669.0 124969.8 126975.0 128630.7 100259.5 107094.9 108766.0 109732.8 109741.4 8979.1 8993.9 9013.7 9107.6 9159.0 59225.0 60936.4 61499.2 62279.0 62492.4 75905.0 87647.6 91447.0 91944.5 92175.5 11911.3 11945.0 12061.7 12154.3 12209.8 13150.7 13225.8 13310.0 13600.3 13749.1 10252.0 10952.8 12173.3 12391.5 12421.2 12382.7 12423.1 12423.5 12625.4 12817.0 10024.3 11995.4 12072.3 12196.5 12498.4 10091.8 10211.5 10327.8 10715.9 10816.4 10219.7 10391.9 10637.0 10666.4 10849.6 10:36:31 INFO - PROCESS | 1663 | -------- Summary: end -------- 10:36:31 INFO - PROCESS | 1663 | 10:36:31 INFO - TEST-INFO | 1663: exit 0 10:36:31 INFO - TEST-OK | dromaeo_css | took 1116149ms 10:36:31 INFO - TEST-START | kraken 10:36:31 INFO - operating with platform_type : mac_ 10:36:31 INFO - Initialising browser for kraken test... 10:36:31 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpNcJBNA/profile http://localhost:49270/getInfo.html) 10:36:34 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 10:36:34 INFO - PROCESS | 1673 | colorDepth:24 10:36:34 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 10:36:34 INFO - PROCESS | 1673 | __metrics 10:36:34 INFO - PROCESS | 1673 | 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" 10:36:34 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:36:34 INFO - TEST-INFO | 1673: exit 0 10:36:34 INFO - Browser initialized. 10:36:34 INFO - Running cycle 1/1 for kraken test... 10:36:34 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpNcJBNA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:36:46 INFO - PROCESS | 1674 | RSS: Main: 242315264 10:36:46 INFO - PROCESS | 1674 | 10:36:49 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 10:36:50 INFO - PROCESS | 1674 | RSS: Main: 291717120 10:36:50 INFO - PROCESS | 1674 | 10:36:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 10:36:53 INFO - PROCESS | 1674 | RSS: Main: 302014464 10:36:53 INFO - PROCESS | 1674 | 10:36:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 10:36:57 INFO - PROCESS | 1674 | RSS: Main: 386375680 10:36:57 INFO - PROCESS | 1674 | 10:36:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 10:37:00 INFO - PROCESS | 1674 | RSS: Main: 288133120 10:37:00 INFO - PROCESS | 1674 | 10:37:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:37:02 INFO - PROCESS | 1674 | RSS: Main: 292024320 10:37:02 INFO - PROCESS | 1674 | 10:37:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 10:37:06 INFO - PROCESS | 1674 | RSS: Main: 330690560 10:37:06 INFO - PROCESS | 1674 | 10:37:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 10:37:09 INFO - PROCESS | 1674 | RSS: Main: 332115968 10:37:09 INFO - PROCESS | 1674 | 10:37:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 10:37:13 INFO - PROCESS | 1674 | RSS: Main: 330608640 10:37:13 INFO - PROCESS | 1674 | 10:37:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:37:15 INFO - PROCESS | 1674 | RSS: Main: 311398400 10:37:15 INFO - PROCESS | 1674 | 10:37:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:37:18 INFO - PROCESS | 1674 | RSS: Main: 318525440 10:37:18 INFO - PROCESS | 1674 | 10:37:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:37:21 INFO - PROCESS | 1674 | RSS: Main: 321581056 10:37:21 INFO - PROCESS | 1674 | 10:37:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:37:24 INFO - PROCESS | 1674 | RSS: Main: 324448256 10:37:24 INFO - PROCESS | 1674 | 10:37:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:37:28 INFO - PROCESS | 1674 | RSS: Main: 324853760 10:37:28 INFO - PROCESS | 1674 | 10:37:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 10:37:30 INFO - PROCESS | 1674 | RSS: Main: 368783360 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | __start_tp_report 10:37:30 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 10:37:30 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 10:37:30 INFO - PROCESS | 1674 | |i|pagename|runs| 10:37:30 INFO - PROCESS | 1674 | |0;ai-astar;110;106;111;111;112;111;112;112;111;112 10:37:30 INFO - PROCESS | 1674 | |1;audio-beat-detection;155;119;117;168;121;126;163;118;153;119 10:37:30 INFO - PROCESS | 1674 | |2;audio-dft;182;187;181;182;184;182;181;183;181;182 10:37:30 INFO - PROCESS | 1674 | |3;audio-fft;82;75;58;105;57;57;61;68;81;71 10:37:30 INFO - PROCESS | 1674 | |4;audio-oscillator;87;88;92;70;70;69;97;70;69;100 10:37:30 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;86;89;87;89;86;90;88;135;131 10:37:30 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;96;95;95;94;95;95;95;94;94 10:37:30 INFO - PROCESS | 1674 | |7;imaging-desaturate;89;86;84;85;85;87;85;69;141;145 10:37:30 INFO - PROCESS | 1674 | |8;json-parse-financial;56;54;55;55;55;55;56;54;53;56 10:37:30 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;67;73;71;74;78;74;71;70;72 10:37:30 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;87;85;87;89;94;107;91;87;123;88 10:37:30 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;144;147;147;145;140;146;144;145;157;139 10:37:30 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;165;166;167;165;169;225;221;166;164;164 10:37:30 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;66;66;67;63;69;80;86;79;66;65 10:37:30 INFO - PROCESS | 1674 | __end_tp_report 10:37:30 INFO - PROCESS | 1674 | __start_cc_report 10:37:30 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1972 10:37:30 INFO - PROCESS | 1674 | __end_cc_report 10:37:30 INFO - PROCESS | 1674 | __startTimestamp1464889050247__endTimestamp 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | ------- Summary: start ------- 10:37:30 INFO - PROCESS | 1674 | Number of tests: 14 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:1.81 (1.6%) stddev-sans-first:1.90 10:37:30 INFO - PROCESS | 1674 | Values: 110.0 106.0 111.0 111.0 112.0 111.0 112.0 112.0 111.0 112.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:139.50 stddev:21.06 (15.1%) stddev-sans-first:21.17 10:37:30 INFO - PROCESS | 1674 | Values: 155.0 119.0 117.0 168.0 121.0 126.0 163.0 118.0 153.0 119.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.00 stddev:1.84 (1.0%) stddev-sans-first:1.94 10:37:30 INFO - PROCESS | 1674 | Values: 182.0 187.0 181.0 182.0 184.0 182.0 181.0 183.0 181.0 182.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:71.50 Median:69.50 stddev:15.13 (21.8%) stddev-sans-first:15.56 10:37:30 INFO - PROCESS | 1674 | Values: 82.0 75.0 58.0 105.0 57.0 57.0 61.0 68.0 81.0 71.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:78.50 stddev:12.80 (16.3%) stddev-sans-first:13.40 10:37:30 INFO - PROCESS | 1674 | Values: 87.0 88.0 92.0 70.0 70.0 69.0 97.0 70.0 69.0 100.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:88.50 stddev:19.04 (21.5%) stddev-sans-first:19.98 10:37:30 INFO - PROCESS | 1674 | Values: 89.0 86.0 89.0 87.0 89.0 86.0 90.0 88.0 135.0 131.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.84 (0.9%) stddev-sans-first:0.67 10:37:30 INFO - PROCESS | 1674 | Values: 93.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 94.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:85.00 stddev:25.58 (30.1%) stddev-sans-first:27.02 10:37:30 INFO - PROCESS | 1674 | Values: 89.0 86.0 84.0 85.0 85.0 87.0 85.0 69.0 141.0 145.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:0.97 10:37:30 INFO - PROCESS | 1674 | Values: 56.0 54.0 55.0 55.0 55.0 55.0 56.0 54.0 53.0 56.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.50 stddev:3.50 (4.8%) stddev-sans-first:3.07 10:37:30 INFO - PROCESS | 1674 | Values: 66.0 67.0 73.0 71.0 74.0 78.0 74.0 71.0 70.0 72.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:87.50 stddev:12.05 (13.8%) stddev-sans-first:12.53 10:37:30 INFO - PROCESS | 1674 | Values: 87.0 85.0 87.0 89.0 94.0 107.0 91.0 87.0 123.0 88.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:145.50 stddev:4.88 (3.4%) stddev-sans-first:5.15 10:37:30 INFO - PROCESS | 1674 | Values: 144.0 147.0 147.0 145.0 140.0 146.0 144.0 145.0 157.0 139.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:166.50 stddev:24.20 (14.5%) stddev-sans-first:25.26 10:37:30 INFO - PROCESS | 1674 | Values: 165.0 166.0 167.0 165.0 169.0 225.0 221.0 166.0 164.0 164.0 10:37:30 INFO - PROCESS | 1674 | 10:37:30 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:68.00 stddev:7.92 (11.6%) stddev-sans-first:8.21 10:37:30 INFO - PROCESS | 1674 | Values: 66.0 66.0 67.0 63.0 69.0 80.0 86.0 79.0 66.0 65.0 10:37:30 INFO - PROCESS | 1674 | -------- Summary: end -------- 10:37:30 INFO - PROCESS | 1674 | 10:37:31 INFO - TEST-INFO | 1674: exit 0 10:37:31 INFO - TEST-OK | kraken | took 59198ms 10:37:31 INFO - SUITE-END | took 1175s 10:37:31 INFO - Completed test suite (00:19:35) 10:37:31 INFO - javascript benchmark 10:37:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2894.797364184507, 3472.392331342016, 3484.878029268976, 3499.7375196860244, 3539.2213712983153, 5576.776928922838, 5641.943580564198, 5653.689047102408, 5690.658560486374, 5715.628484148533, 4757.809687612491, 5655.4061823508555, 5670.744816483248, 5792.4497172768515, 5821.27234095738, 5062.038212739586, 5089.643724939256, 5142.0, 5155.510226528492, 5257.8948421031555, 4417.668674849399, 5292.444293349201, 5293.550048245885, 5320.813771517997, 5359.705216213107, 5101.183810590288, 5139.12634852076, 5166.9224961625605, 5200.01199772044, 5208.932168905365, 5152.098382782998, 5197.610179236573, 5200.557952574036, 5203.323567936182, 5207.895842083175, 5129.717865517395, 5133.153029750087, 5174.689518628857, 5212.374515058179, 5248.0, 5622.409646987048, 5676.687782171979, 5715.942840571583, 5719.48524632785, 5725.312962444492, 6059.099586382425, 6256.280527739323, 6264.530160237955, 6271.184745983039, 6278.717457714368, 6214.067889816519, 6214.720337584819, 6253.374662533755, 6257.468115210213, 6257.968710156466, 5808.564357673192, 5850.0932355484965, 5853.5902486825935, 5868.149118377854, 5874.295084589791, 6213.875722485525, 6214.5960512566535, 6277.2153480814895, 6322.589031712924, 6336.112944187817, 6138.202033735586, 6164.198654174928, 6176.69116544171, 6177.876442471125, 6179.567430279837, 6254.6872656366995, 6264.498840092781, 6268.122462855204, 6275.121482992385, 6283.3088360280335, 6078.817635470943, 6106.1146133811235, 6213.627182369072, 6223.844403889939, 6232.875342493125, 6121.696863452088, 6138.120194445427, 6191.628502289877, 6215.875682486325, 6251.0, 4974.452810190876, 4975.601951843843, 4977.776000080005, 4983.651144419856, 5006.173981293119, 4416.6025057744955, 4481.081378317437, 4499.775011249424, 4505.459344878635, 4516.570925762047, 5622.746976386073, 5674.858128546737, 5712.428757124254, 5742.368339482654, 5747.281589801275, 6268.1851359323, 6268.561200715997, 6296.685165741695, 6296.937030629727, 6303.054541818764, 5547.611667183339, 5643.238162848045, 5699.401562835926, 5722.62802917809, 5723.54211663066, 5367.5169234769055, 5372.758225879845, 5432.565394768409, 5445.37378201504, 5445.428230035789, 6338.968305158444, 6348.079528468346, 6394.488440924808, 6397.0, 6415.037744338293, 6246.781362652286, 6257.155284036597, 6269.31037585856, 6285.59143655661, 6286.905696414466, 6226.25284965807, 6303.590266632654, 6348.587341822767, 6384.201974753156, 6390.041493775971, 6147.0, 6202.596831205957, 6208.658523781235, 6209.720562574605, 6214.850252703264, 6240.063990401476, 6244.937550624436, 6263.0, 6269.68651567429, 6274.780382686584, 5968.850778730392, 6035.305939816977, 6054.969725151346, 6082.391760823883, 6128.785492507741, 5925.496332811586, 6043.214382130295, 6045.274567051806, 6100.206973093469, 6103.877922441615, 5964.7912323068485, 5965.731542080704, 5975.551833612583, 5997.490213331916, 6042.1540984261355, 5954.285485741739, 6024.759009639565, 6096.458138686707, 6103.450689437972, 6117.084826762104, 5947.078202878561, 6029.427204415573, 6032.75868965254, 6035.6076855004285, 6088.756449742138], "value": 5703.52587832809, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.77097652703924, 507.8831868670206, 514.6191818054641, 525.9526642602167, 549.5466240351711, 30656.69343306569, 32027.0, 32114.678853211462, 32126.0, 32199.0, 32706.0, 43392.78303608477, 44686.32970505445, 45103.548964510344, 47648.28527572089, 9417.43495390277, 9590.9520452398, 9601.951990240039, 9605.567749451257, 9616.0, 8532.658693652247, 9445.433274003548, 9575.377600455948, 9598.90401095987, 9627.277954153431, 8626.870596941051, 11487.59793407231, 11679.416029198506, 11720.413979301044, 11967.922886940174, 11524.962753352196, 11694.824577631258, 11698.298102113815, 11719.589814356457, 11752.23610465317, 8452.112528184574, 11446.885531144666, 11490.253133546294, 11591.130665200059, 11821.822672659917, 41392.58607413917, 41649.0, 41886.0, 41943.1611367774, 41998.58001420008, 3504.929901401983, 3823.445600387928, 4206.894827629303, 4241.0, 4365.105153443536, 31589.8420507896, 31868.379085032542, 32741.761318188976, 32868.178295542566, 33130.0, 3164.5886034815326, 3298.224917144469, 3312.337532493511, 3329.7003269705842, 3334.049795808183, 454964.9999999967, 457534.0, 460649.0, 460817.0, 461992.0, 65396.0, 65427.67286163539, 65713.34286657168, 65777.01334479987, 65798.34201658018, 69364.6531767348, 69463.0, 69468.65265673639, 69510.95733564, 69609.65195173991, 16284.348626055062, 16934.40729574459, 16938.49184524466, 16957.576060598585, 16971.91514042422, 12971.41628626714, 13040.608781736562, 13045.804312935314, 13066.738665226832, 13093.934530327479, 15790.605234869221, 16049.759253611206, 18150.636987260183, 18439.0, 18705.90647046783, 5980.910286345708, 6153.476954458832, 6167.876642467126, 7418.295261950106, 7456.7762967111075, 903.3270213690843, 948.9952550237205, 1017.6743442098606, 1065.4512925843196, 1080.5353697909818, 26830.731692682824, 29031.0, 31020.844895775375, 31084.689153108633, 31281.48040913953, 1054.8206804843196, 1084.3385534823751, 1162.5291756838494, 1162.773259214462, 1236.5053978408528, 12525.93737031309, 15215.923920380328, 15808.68382632341, 15970.0, 16414.589635258973, 176063.0, 177356.0, 177727.0, 177963.0, 178340.10829945767, 62556.68721656453, 62716.0, 62725.0, 62833.0, 62885.37114628887, 1374.0793668242302, 1424.0601203205833, 1443.364919435445, 1443.9278036098153, 1446.1250943179646, 30272.243193920327, 30332.69667303387, 30515.694843051286, 30899.382012360078, 31113.066608001795, 54031.459685402646, 54241.18638220351, 54559.454405455435, 54585.0, 54712.726436367564, 502743.0, 586362.0, 587493.0, 588453.0, 589616.0, 26285.4742905137, 26298.86850565735, 26585.335366615214, 28476.0, 42774.57225427706, 35429.1142721434, 41054.178916422105, 48724.26913596228, 50858.49141508686, 52422.427327180245, 40295.59704403036, 41197.382039270036, 41251.76244712663, 41914.0, 42132.78933605435, 39571.0, 40134.397984030846, 40189.79905100573, 40518.0, 41147.7942610285, 38887.41668875026, 39524.40713389244, 39853.20293594054, 39883.0, 39905.0, 33510.497342540366, 35075.82462087776, 35335.469967951016, 35753.927382178575, 35780.105497362776, 21359.25242616501, 25546.489070218864, 25985.610215847155, 26001.699915004552, 26479.0], "value": 19866.003215310167, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.8224584062066, 358.25840510143996, 376.206204907645, 380.0858934263097, 385.33529661334194, 20029.499262518428, 20693.689594656054, 21046.684299735498, 21124.788752112476, 21535.784642153554, 20307.08618112185, 20634.1746330147, 20908.58182836346, 21131.943402829835, 21230.787692123075, 5165.664231824938, 5839.854003649911, 6079.088136779475, 6096.664683442431, 6108.0, 5895.9705201473935, 6016.578839481238, 6036.275646922375, 6052.697365131748, 6056.666883321415, 5750.683712395817, 5933.706516207767, 5954.047352423613, 5959.195508606326, 5961.254843144607, 5737.540996720252, 5900.911486327708, 5913.172155898178, 5916.526677865761, 5916.733746981375, 5069.138246498067, 5180.197069454203, 5203.0, 5205.375354957391, 5207.843764687065, 24073.398165045837, 25045.49909001828, 25670.358241043938, 25772.0, 25981.960721571115, 2095.528506086134, 2221.922232721863, 2826.0, 2960.792744507864, 3089.8609562569513, 9669.0, 10079.69760907174, 10480.528376223088, 10483.056524912718, 11147.498362573704, 1785.8392744652913, 1826.5981484073482, 1858.219547789918, 1881.9905900470546, 1895.0240626077582, 69998.0, 115407.42296288548, 119923.4003829984, 133302.3334883329, 135180.0, 9188.816223675623, 11690.298582084932, 11758.35329056893, 11895.821562676567, 11907.464164112462, 12949.158304710163, 13550.796738048937, 13717.588472345846, 14525.927370363292, 14632.268386580628, 7695.538267703956, 7870.4652592745015, 8619.138086191457, 8676.826463470696, 8707.172818582225, 8574.185452382013, 8578.828423431622, 8627.415721357289, 8679.392442529088, 8695.000149965543, 4464.06254686513, 4538.591526762541, 4541.909161816745, 4588.655850811202, 4629.328747331618, 1885.2459016393552, 1915.9042047897549, 1930.845532357408, 1935.7096435534781, 1974.2497850816599, 773.4894969320334, 936.5644975086617, 960.2701946520694, 1031.1029404418055, 1052.9368237905596, 15171.696566068616, 19301.324453644054, 20674.896625516776, 20957.47606309824, 21227.78772212289, 809.874469457235, 965.5654955270016, 965.9178969787647, 972.9659461918799, 1067.7597540553313, 10244.64143754965, 10947.069499092666, 10975.615853445091, 10995.0, 11020.393878336608, 540014.0, 596959.0, 606860.0, 609278.0, 610403.0, 126609.0, 131161.0, 135039.32480337535, 135716.0, 136458.6354136446, 989.3766926836047, 990.841465365538, 1011.1758916483043, 1012.372329155928, 1012.767063575367, 16333.67332653364, 16411.0, 17194.882332648336, 17896.10519474047, 18274.269029238683, 51374.972500550524, 51389.486105139964, 51560.484395157066, 52995.47004529905, 53373.7331313341, 76898.46203076019, 77632.0, 78003.0, 78373.97891278623, 78626.6068669653, 7756.185600512091, 7853.253940875607, 7920.564368959763, 7941.563214023284, 8120.431569790058, 8872.29021678277, 11721.413929303671, 11917.404129793302, 12032.639020829389, 12263.75472490563, 10403.42781147014, 10424.635137770141, 10584.4707764613, 10634.787304253718, 10667.306625069346, 10125.341852779546, 10455.58177672915, 10475.266731328733, 10514.474276286002, 10591.576336946744, 10565.0, 10629.734256643645, 10789.946050269698, 10818.729531761768, 10825.621103261348, 6017.398260173947, 7831.960840195762, 7909.644066017158, 7928.816316753353, 8033.3171680405485, 7894.210578942289, 9424.670136545188, 10486.737831554272, 10549.683509494727, 10685.412302323446], "value": 9435.382395163811, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.6048176269765, 1537.9077255364675, 1568.6705791783725, 1783.9197236124373, 1923.7595300587418, 7714.49855759376, 8420.536870472122, 8631.48211107334, 8632.784180395493, 8750.737477875675, 8419.621117049732, 8610.440321379107, 8615.43999640023, 8618.0, 8620.094890036535, 2344.66002429648, 2378.940526486834, 2379.274321331993, 2379.333786539763, 2382.0233704181287, 2328.1851352026824, 2339.9181028664, 2369.2063158841725, 2370.7510711375317, 2372.1815973489206, 2323.9651405228933, 2345.8240631952585, 2358.9646155307682, 2362.7400985891536, 2364.6216605343147, 2022.656148454759, 2267.2744721688982, 2306.077568972408, 2331.1491305673394, 2353.364591560286, 2311.2719493359537, 2342.730585982617, 2347.917822876208, 2349.4361353275262, 2352.282553821084, 8501.23488885997, 8526.531040792754, 8604.483730976099, 8625.65497380104, 8720.171583699603, 5195.610329225286, 5343.0, 5343.198520221959, 5348.732563371816, 5351.812686555952, 3463.1169051891857, 3495.7028652564563, 3555.8933232002823, 3557.839897204632, 3611.5846677632144, 1054.7995880782628, 1069.903708666216, 1077.9083777878889, 1084.0514549768952, 1096.1723898456614, 11325.0, 11439.256448330832, 11445.0, 11445.599404020819, 11455.427228638535, 4459.818148190732, 4460.0, 4580.221362368406, 4594.701344412602, 4598.195315819718, 7659.731909383145, 9038.593263303168, 9039.593218305192, 9043.864342034874, 9062.81874362509, 5086.18621020635, 5164.25118357836, 5180.067587834226, 5181.4300426953005, 5182.041322355376, 4331.6534677225745, 4487.618552423015, 4653.371794807657, 4815.374001379798, 4876.780544875489, 3150.763692723055, 3750.8124593770203, 3823.9044023899623, 3842.4620553122586, 3851.7111216658864, 978.3542861711236, 1156.9537218511166, 1162.5640384855678, 1172.8709841917382, 1174.518447436547, 661.3320546248227, 806.2783808491367, 878.3456325037888, 888.5246393179747, 990.2375171118199, 5876.471117599436, 5995.640261584319, 6005.3394126646035, 6039.1545183674325, 6179.073139029181, 906.582971832951, 908.9409188402733, 910.2854259406466, 915.706973768401, 929.5677509957902, 3885.3589157788647, 3983.0440694233753, 3998.1803730235233, 4000.6599439047427, 4056.452378928807, 8513.872291915626, 10529.36823790575, 10650.094741947021, 10653.84019239712, 10653.893461065445, 10553.155747540179, 10635.149188064936, 10684.412357320422, 10688.893111068945, 10695.037446629685, 7759.107702614158, 8009.959950200212, 8112.269895709533, 8177.059638141571, 8180.059293181242, 5048.444671086251, 5919.378465261086, 5931.347551769216, 6030.48740857032, 6076.665783381956, 9184.173424391836, 9590.232781377612, 9839.655612053544, 9844.60621575129, 9918.603255869974, 9778.462184579916, 9999.0, 10008.44953527563, 10122.190224781853, 10171.64399246023, 7425.628718564159, 7469.7385591504035, 7536.811579710551, 7558.168601453725, 7593.734219302298, 8073.15231900642, 8147.511149331059, 8149.755507334789, 8152.3478121751305, 8158.1026087129185, 4878.170710979071, 7044.295570442915, 7288.453365997465, 7426.5915374654915, 7541.5475071495885, 7232.74685386009, 7329.596872172082, 7332.340089391981, 7435.8141046474275, 7442.851142977219, 6909.2017732818285, 6938.098047253825, 7026.789196324118, 7050.858982820418, 7056.259092795388, 4943.950560494349, 4953.900921981468, 5001.174806156943, 5024.547790698854, 5091.6181286402925, 4966.0, 4985.1276026695905, 5023.974880125576, 5024.120778863758, 5122.820701275437], "value": 4496.863379933798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.28727490397563, 313.1310613048789, 318.4157071773295, 320.0175461333707, 322.87246537617625, 5166.457521960192, 5323.22813192087, 5366.543843773284, 5374.6237763356585, 5407.351117865852, 4596.586307232348, 5266.394364648067, 5334.9987509367975, 5355.277037599923, 5361.624686271963, 3129.499280115182, 3422.117835190648, 3777.7166712496605, 3939.704522160835, 4035.4148648445957, 3401.5203386526814, 3965.583613720561, 4018.7990600470007, 4035.3341698619693, 4105.3431450967855, 3247.4249988755555, 3969.2458432897656, 4029.697772667047, 4040.1717647882115, 4047.6964227683043, 3423.7674437202586, 3813.9427908581392, 3924.2151569686175, 3944.5799512175736, 4036.636702696742, 2945.337299107705, 3085.490894002487, 3525.7179425645886, 3527.9294414111832, 3550.378683730337, 3960.564337922855, 4027.1140349123148, 4883.633727470454, 5023.4474207837475, 5084.567811736007, 3650.9269814603826, 3924.8626298079716, 4479.910401791978, 4628.0, 4695.88260293492, 5072.315237442935, 5507.394186639466, 5508.256385387961, 5535.557155427597, 5546.4453554644615, 3475.2701932594186, 3511.8568880851494, 3886.24218277436, 3918.235943990923, 3923.0780766519847, 5198.714070726109, 5477.589180811455, 5558.861028474321, 5704.71476426177, 5829.009068458372, 3207.0, 3230.9838450807597, 3337.6996070353784, 3656.9760467069264, 3848.7690738555775, 3317.6350601433824, 3922.2351641429645, 3936.547297060875, 3944.3097457944746, 3949.980250098731, 4323.265044942367, 4574.078886867524, 4575.267957126844, 4593.586577208067, 4611.515790841981, 5238.449962753856, 5323.068463018956, 5470.562355011669, 5608.663480191201, 5609.579281553818, 1124.0501775999267, 1237.9814302785464, 1267.2776517385003, 1296.682312833362, 1309.6594885329882, 781.4803155901262, 804.3806269172704, 805.778410936997, 810.9133760760491, 817.7791996161003, 4172.895677608023, 4340.435743353407, 4652.953470465252, 4667.789949452283, 5117.232415137684, 5394.599403265509, 5546.473085056994, 5751.8562035949435, 5802.82591522255, 5835.533157347402, 4160.313548264502, 4535.324158746355, 4815.518448155157, 4955.702657840541, 5228.607854410934, 2729.426820367745, 2811.4236581500745, 2861.697927443008, 3011.0040509692994, 3021.7884748067863, 5160.329157209539, 5505.752241149158, 5811.663317437014, 5987.012987012987, 6020.367861374581, 4499.617532509707, 5414.945850541523, 5818.592698511148, 5936.762529498858, 5947.910781338283, 5636.815199412319, 5932.3474417814, 5974.9103763444455, 5988.461038506555, 6000.309964354155, 4499.910001800011, 4991.750412479434, 5025.623078269217, 5091.821786237464, 5768.730879206568, 5237.921431178459, 5429.891402172014, 5532.861678458071, 5680.488756011979, 5718.085106382959, 4879.414470263591, 5499.037668407933, 5556.832835194358, 5937.910931335947, 6015.789447369321, 3917.451556781996, 3918.1184233547224, 3943.566207717206, 3945.585713500041, 3973.264945985002, 5148.665336753099, 5157.690538567698, 5324.36107667067, 5481.972590137183, 5653.40639232891, 4670.1126785910565, 4936.802527898987, 5002.599792016557, 5521.944780552304, 5568.387477377404, 5276.443449182577, 5351.628488827447, 5459.508644222039, 5459.590530710292, 5520.889582208413, 4857.902841943071, 4984.00319936007, 5362.0, 5390.946090539202, 5400.378956419905, 4516.8193272268745, 5258.8948221036135, 5261.579373569039, 5444.863878403072, 5501.514591060311, 4910.74775932135, 5297.337832770903, 5360.65155764874, 5370.516653501204, 5402.135658294654], "value": 3977.529695531178, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.6026225313824, 711.5695004522264, 736.4108713029576, 741.9777406677798, 759.8176437654964, 15418.61453463664, 15446.304916278767, 15710.978786378877, 15717.842821571781, 15718.135502547355, 12480.375981200948, 15865.52403427899, 15911.045337279771, 16036.0, 16044.197790110476, 699.965001749913, 876.8071024374659, 879.2174964281794, 887.3655336434448, 887.4497811356968, 717.4583189691775, 869.3436455476107, 900.5092224737534, 900.5182227508284, 904.2404380320529, 942.3215284994827, 942.6229508196708, 949.3070058857038, 950.2872845365976, 952.4999375327939, 732.3116270705519, 913.4610579757908, 943.8395472769646, 944.924406047518, 945.4327403557879, 689.2108535726616, 690.7582346178797, 692.2800287700785, 693.0643631097977, 698.1447726535005, 15702.607434814106, 15788.447404340908, 15795.526134215877, 15798.842011579853, 15804.288807003712, 11841.467133978991, 13558.593242202749, 13575.253361065137, 13816.585502434944, 13953.581392558139, 11097.55609775608, 12999.484147454456, 13223.537176198786, 13460.259685717281, 13798.241096739675, 4519.254323036695, 4595.356650068993, 4654.25531914892, 4716.153743242645, 4717.00942802012, 84946.0, 112871.0, 115087.42456287748, 115594.0, 115643.843561565, 10246.0, 11201.887981120248, 11514.30914145154, 11810.822837657443, 11960.983316418027, 22125.66811497829, 22570.774292257196, 22571.0, 22576.20983265578, 22581.887090564443, 7199.424046076301, 8293.751187464386, 8308.584570771438, 8328.500289982621, 8500.574971251413, 3946.3291240489184, 4445.555444455593, 4501.752403617833, 4546.727196368229, 4682.157211701859, 3587.3901436755814, 3666.211764470652, 3707.796071216109, 3709.1283548366114, 3709.3694072007825, 656.5699466849221, 668.5654324688894, 675.392147067643, 679.49038221334, 716.8494616130566, 558.5392051557482, 573.0830670926484, 593.7981086430634, 694.8054544727485, 722.8391460411995, 9512.714618561455, 10300.330478519023, 10627.84058239127, 10739.570417183382, 10746.570137194425, 611.9696098073827, 632.7152781248365, 675.7128220506363, 683.2859661653584, 698.5599072584341, 4508.616767574793, 4547.727136371828, 4602.332661764092, 4620.768961551909, 4648.279516674921, 92917.07082929219, 122767.77232227742, 125420.37289813493, 139982.30008849892, 144769.0, 95882.0, 108669.0, 124969.75030249581, 126975.0, 128630.71369286375, 100259.49870250603, 107094.85810283906, 108766.0, 109732.80534389267, 109741.35387969187, 8979.14698103679, 8993.865092023494, 9013.729588112366, 9107.58832380982, 9158.95420522893, 59225.0, 60936.39063609307, 61499.235084139815, 62279.0, 62492.37507624865, 75905.0, 87647.56176219079, 91447.0, 91944.54027729819, 92175.53912230396, 11911.344876031555, 11944.98467630261, 12061.733517980485, 12154.331511766937, 12209.755804884031, 13150.671233218864, 13225.801612976005, 13310.0, 13600.251986140462, 13749.106308089942, 10252.026057524523, 10952.780944381228, 12173.2696038238, 12391.504339826666, 12421.19262247951, 12382.690432739253, 12423.130380873252, 12423.503059877865, 12625.368731563569, 12817.0, 10024.298299118991, 11995.40022998864, 12072.3360215189, 12196.51213951432, 12498.37508124572, 10091.798164036825, 10211.540480678535, 10327.793444131226, 10715.946420267848, 10816.4050977197, 10219.693409197736, 10391.896081039093, 10637.042666160083, 10666.413347265974, 10849.566017359217], "value": 7761.483054681493, "unit": "score"}], "name": "dromaeo_css", "value": 7276.482384634623, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 111.0, 111.0, 112.0, 111.0, 112.0, 112.0, 111.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 117.0, 168.0, 121.0, 126.0, 163.0, 118.0, 153.0, 119.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 181.0, 182.0, 184.0, 182.0, 181.0, 183.0, 181.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 58.0, 105.0, 57.0, 57.0, 61.0, 68.0, 81.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 92.0, 70.0, 70.0, 69.0, 97.0, 70.0, 69.0, 100.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 88.0, 135.0, 131.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 84.0, 85.0, 85.0, 87.0, 85.0, 69.0, 141.0, 145.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 55.0, 55.0, 55.0, 56.0, 54.0, 53.0, 56.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 73.0, 71.0, 74.0, 78.0, 74.0, 71.0, 70.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 89.0, 94.0, 107.0, 91.0, 87.0, 123.0, 88.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 147.0, 145.0, 140.0, 146.0, 144.0, 145.0, 157.0, 139.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 167.0, 165.0, 169.0, 225.0, 221.0, 166.0, 164.0, 164.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 63.0, 69.0, 80.0, 86.0, 79.0, 66.0, 65.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1482.7000000000003, "alertThreshold": 2.0}]} 10:37:31 INFO - Return code: 0 10:37:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:37:31 ERROR - # TBPL SUCCESS # 10:37:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:37:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:37:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:37:31 INFO - 2016-06-02 10:37:31.563 screenresolution[1678:9597] starting screenresolution argv=screenresolution get 10:37:31 INFO - 2016-06-02 10:37:31.566 screenresolution[1678:9597] Display 0: 1600x1200x32@60 10:37:31 INFO - 2016-06-02 10:37:31.572 screenresolution[1679:9600] starting screenresolution argv=screenresolution list 10:37:31 INFO - Available Modes on Display 0 10:37:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:37:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:37:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:37:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:37:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:37:31 INFO - Intel Iris: 10:37:31 INFO - Chipset Model: Intel Iris 10:37:31 INFO - Type: GPU 10:37:31 INFO - Bus: Built-In 10:37:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:37:31 INFO - Vendor: Intel (0x8086) 10:37:31 INFO - Device ID: 0x0a2e 10:37:31 INFO - Revision ID: 0x0009 10:37:31 INFO - Displays: 10:37:31 INFO - Display: 10:37:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:37:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:37:31 INFO - Display Serial Number: 1600x1200 60 10:37:31 INFO - Main Display: Yes 10:37:31 INFO - Mirror: Off 10:37:31 INFO - Online: Yes 10:37:31 INFO - Rotation: Supported 10:37:31 INFO - Return code: 0 10:37:31 INFO - Running post-action listener: _resource_record_post_action 10:37:31 INFO - ##### 10:37:31 INFO - ##### Finished run-tests step (success) 10:37:31 INFO - ##### 10:37:31 INFO - Running post-run listener: _resource_record_post_run 10:37:31 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37885440; Write bytes: 698031616; Read time: 391; Write time: 1973 10:37:31 INFO - TinderboxPrint: CPU usage
53.6% 10:37:31 INFO - TinderboxPrint: I/O read bytes / time
37,885,440 / 391 10:37:31 INFO - TinderboxPrint: I/O write bytes / time
698,031,616 / 1,973 10:37:31 INFO - TinderboxPrint: CPU idle
2,249.9 (46.4%) 10:37:31 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 10:37:31 INFO - TinderboxPrint: CPU user
1,630.6 (33.7%) 10:37:31 INFO - TinderboxPrint: Swap in / out
437,342,208 / 0 10:37:31 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393632256; Write bytes: 388134400; Read time: 29450; Write time: 735 10:37:31 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36042240; Write bytes: 294377472; Read time: 370; Write time: 1170 10:37:32 INFO - Running post-run listener: _upload_blobber_files 10:37:32 INFO - Blob upload gear active. 10:37:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:37:32 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 10:37:32 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'] 10:37:32 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 10:37:32 INFO - (blobuploader) - INFO - Open directory for files ... 10:37:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:37:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:37:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:37:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:37:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:37:34 INFO - (blobuploader) - INFO - Done attempting. 10:37:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:37:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:37:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:37:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:37:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:37:35 INFO - (blobuploader) - INFO - Done attempting. 10:37:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:37:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:37:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:37:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:37:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:37:40 INFO - (blobuploader) - INFO - Done attempting. 10:37:40 INFO - (blobuploader) - INFO - Iteration through files over. 10:37:40 INFO - Return code: 0 10:37:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:37:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:37:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d8f0f77c28d17d6323fd1f7f610d363add11c283a7aeb8491c7360078f364d8b0931ed33b5e204d97363229e115dc8a3ae6122c1a21369577179cdcbced72cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70c879b2242df405bf62d59ba2150688204a52cd57810072a5e7b5b6231f047bcd80152c29a437e7fc97f7b4dfbe53bb3c65bdeb2c9d82c1a84fb30cba044135", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972bc8be5c462ce89502f200d0e1137d227792cc8c3f5ea29b1d9a75bee15009dae0bbdc2a9ee5a613b4d20bed762225bc610564b36e2bbe53f272ca8bacacec"} 10:37:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:37:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:37:40 INFO - Contents: 10:37:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d8f0f77c28d17d6323fd1f7f610d363add11c283a7aeb8491c7360078f364d8b0931ed33b5e204d97363229e115dc8a3ae6122c1a21369577179cdcbced72cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70c879b2242df405bf62d59ba2150688204a52cd57810072a5e7b5b6231f047bcd80152c29a437e7fc97f7b4dfbe53bb3c65bdeb2c9d82c1a84fb30cba044135", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972bc8be5c462ce89502f200d0e1137d227792cc8c3f5ea29b1d9a75bee15009dae0bbdc2a9ee5a613b4d20bed762225bc610564b36e2bbe53f272ca8bacacec"} 10:37:40 INFO - Running post-run listener: copy_logs_to_upload_dir 10:37:40 INFO - Copying logs to upload dir... 10:37:40 INFO - mkdir: /builds/slave/test/build/upload/logs 10:37:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.686383 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-06-02 10:37:40.326069) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 10:37:40.329924) ========= 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.5QgYsJhY0L/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.svK5jVQkUC/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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/7d8f0f77c28d17d6323fd1f7f610d363add11c283a7aeb8491c7360078f364d8b0931ed33b5e204d97363229e115dc8a3ae6122c1a21369577179cdcbced72cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70c879b2242df405bf62d59ba2150688204a52cd57810072a5e7b5b6231f047bcd80152c29a437e7fc97f7b4dfbe53bb3c65bdeb2c9d82c1a84fb30cba044135", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972bc8be5c462ce89502f200d0e1137d227792cc8c3f5ea29b1d9a75bee15009dae0bbdc2a9ee5a613b4d20bed762225bc610564b36e2bbe53f272ca8bacacec"} build_url:https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010941 build_url: 'https://queue.taskcluster.net/v1/task/K3V4d3SBQBuxPFS0_3mZVA/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/7d8f0f77c28d17d6323fd1f7f610d363add11c283a7aeb8491c7360078f364d8b0931ed33b5e204d97363229e115dc8a3ae6122c1a21369577179cdcbced72cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70c879b2242df405bf62d59ba2150688204a52cd57810072a5e7b5b6231f047bcd80152c29a437e7fc97f7b4dfbe53bb3c65bdeb2c9d82c1a84fb30cba044135", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/972bc8be5c462ce89502f200d0e1137d227792cc8c3f5ea29b1d9a75bee15009dae0bbdc2a9ee5a613b4d20bed762225bc610564b36e2bbe53f272ca8bacacec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 10:37:40.359264) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:37:40.359562) ========= 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.5QgYsJhY0L/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.svK5jVQkUC/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.005044 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:37:40.407876) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 10:37:40.408158) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 10:37:40.408454) ========= ========= Total master_lag: 0.16 =========