builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0262 starttime: 1464695948.96 results: success (0) buildid: 20160531025850 builduid: 0d5945afc0714e94abf9ebc5f63ad40c revision: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:08.957203) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:08.957689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:08.957984) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:09.025355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:09.025786) ========= 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.0AYo5WJDEF/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.gswTJgJtLl/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 04:59:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.6M=0.001s 2016-05-31 04:59:09 (20.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172239 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:09.239117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:09.239463) ========= 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.0AYo5WJDEF/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.gswTJgJtLl/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.027214 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:09.292105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-31 04:59:09.292488) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dc4d7f68030e9edd22ad0bb0bd2244d047dd767d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dc4d7f68030e9edd22ad0bb0bd2244d047dd767d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0AYo5WJDEF/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.gswTJgJtLl/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 04:59:09,372 truncating revision to first 12 chars 2016-05-31 04:59:09,372 Setting DEBUG logging. 2016-05-31 04:59:09,372 attempt 1/10 2016-05-31 04:59:09,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dc4d7f68030e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 04:59:11,175 unpacking tar archive at: mozilla-central-dc4d7f68030e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.068403 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-31 04:59:11.380737) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:11.381056) ========= script_repo_revision: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:11.381478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:11.381745) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 04:59:11.398234) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 46 secs) (at 2016-05-31 04:59:11.398691) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0AYo5WJDEF/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.gswTJgJtLl/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 04:59:11 INFO - MultiFileLogger online at 20160531 04:59:11 in /builds/slave/test 04:59:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:59:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:11 INFO - {'append_to_log': False, 04:59:11 INFO - 'base_work_dir': '/builds/slave/test', 04:59:11 INFO - 'blob_upload_branch': 'Firefox', 04:59:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:11 INFO - 'branch': 'Firefox', 04:59:11 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:11 INFO - 'config_files': ('talos/mac_config.py',), 04:59:11 INFO - 'default_actions': ('clobber', 04:59:11 INFO - 'read-buildbot-config', 04:59:11 INFO - 'download-and-extract', 04:59:11 INFO - 'populate-webroot', 04:59:11 INFO - 'create-virtualenv', 04:59:11 INFO - 'install', 04:59:11 INFO - 'run-tests'), 04:59:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:11 INFO - 'download_minidump_stackwalk': True, 04:59:11 INFO - 'download_symbols': 'ondemand', 04:59:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:11 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:11 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:11 INFO - 'installer_path': 'installer.exe', 04:59:11 INFO - 'log_level': 'info', 04:59:11 INFO - 'log_name': 'talos', 04:59:11 INFO - 'log_to_console': True, 04:59:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:11 INFO - 'opt_config_files': (), 04:59:11 INFO - 'pip_index': False, 04:59:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:11 INFO - 'cmd': ('bash', 04:59:11 INFO - '-c', 04:59:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:11 INFO - 'enabled': True, 04:59:11 INFO - 'halt_on_failure': False, 04:59:11 INFO - 'name': 'check_screen_resolution'},), 04:59:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:11 INFO - 'cmd': ('bash', 04:59:11 INFO - '-c', 04:59:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:11 INFO - 'enabled': True, 04:59:11 INFO - 'halt_on_failure': False, 04:59:11 INFO - 'name': 'check_screen_resolution'},), 04:59:11 INFO - 'run_cmd_checks_enabled': True, 04:59:11 INFO - 'sps_profile': False, 04:59:11 INFO - 'sps_profile_interval': 0, 04:59:11 INFO - 'suite': 'dromaeojs', 04:59:11 INFO - 'system_bits': '32', 04:59:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:11 INFO - 'title': 't-yosemite-r7-0262', 04:59:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:11 INFO - 'use_talos_json': True, 04:59:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:11 INFO - 'work_dir': 'build'} 04:59:11 INFO - ##### 04:59:11 INFO - ##### Running clobber step. 04:59:11 INFO - ##### 04:59:11 INFO - Running pre-action listener: _resource_record_pre_action 04:59:11 INFO - Running main action method: clobber 04:59:11 INFO - rmtree: /builds/slave/test/build 04:59:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:13 INFO - Running post-action listener: _resource_record_post_action 04:59:13 INFO - ##### 04:59:13 INFO - ##### Finished clobber step (success) 04:59:13 INFO - ##### 04:59:13 INFO - ##### 04:59:13 INFO - ##### Running read-buildbot-config step. 04:59:13 INFO - ##### 04:59:13 INFO - Running pre-action listener: _resource_record_pre_action 04:59:13 INFO - Running main action method: read_buildbot_config 04:59:13 INFO - Using buildbot properties: 04:59:13 INFO - { 04:59:13 INFO - "project": "", 04:59:13 INFO - "product": "firefox", 04:59:13 INFO - "script_repo_revision": "production", 04:59:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:59:13 INFO - "repository": "", 04:59:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:59:13 INFO - "buildid": "20160531025850", 04:59:13 INFO - "pgo_build": "False", 04:59:13 INFO - "basedir": "/builds/slave/test", 04:59:13 INFO - "buildnumber": 4, 04:59:13 INFO - "slavename": "t-yosemite-r7-0262", 04:59:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:59:13 INFO - "platform": "yosemite_r7", 04:59:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:59:13 INFO - "branch": "mozilla-central", 04:59:13 INFO - "repo_path": "mozilla-central", 04:59:13 INFO - "revision": "dc4d7f68030e9edd22ad0bb0bd2244d047dd767d", 04:59:13 INFO - "stage_platform": "macosx64", 04:59:13 INFO - "builduid": "0d5945afc0714e94abf9ebc5f63ad40c", 04:59:13 INFO - "slavebuilddir": "test" 04:59:13 INFO - } 04:59:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:59:13 INFO - Running post-action listener: _resource_record_post_action 04:59:13 INFO - ##### 04:59:13 INFO - ##### Finished read-buildbot-config step (success) 04:59:13 INFO - ##### 04:59:13 INFO - ##### 04:59:13 INFO - ##### Running download-and-extract step. 04:59:13 INFO - ##### 04:59:13 INFO - Running pre-action listener: _resource_record_pre_action 04:59:13 INFO - Running main action method: download_and_extract 04:59:13 INFO - mkdir: /builds/slave/test/build/tests 04:59:13 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')]} 04:59:13 INFO - https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:59:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:59:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:59:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:38 INFO - Downloaded 1199 bytes. 04:59:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:59:38 INFO - Using the following test package requirements: 04:59:38 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:59:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:59:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'jsshell-mac.zip'], 04:59:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:59:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:59:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:59:38 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:59:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:59:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:59:38 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59: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')]} 04:59:38 INFO - https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:59:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:59:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:59:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:51 INFO - Downloaded 18684760 bytes. 04:59:51 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'] 04:59:51 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 04:59:54 INFO - Return code: 0 04:59:54 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 04:59: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')]} 04:59:54 INFO - https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:55 INFO - Downloaded 18684760 bytes. 04:59:55 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'] 04:59:55 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 04:59:59 INFO - Return code: 0 04:59:59 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')]} 04:59:59 INFO - https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:59:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:59:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:59:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 04:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 05:00:00 INFO - Downloaded 10834055 bytes. 05:00:00 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'] 05:00:00 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 05:00:00 INFO - Return code: 0 05:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:00 INFO - https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:00:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:00:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:00:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/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 05:00:04 INFO - Downloaded 110925669 bytes. 05:00:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:00:04 INFO - mkdir: /builds/slave/test/properties 05:00:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:00:04 INFO - Writing to file /builds/slave/test/properties/build_url 05:00:04 INFO - Contents: 05:00:04 INFO - build_url:https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:00:05 INFO - Running post-action listener: _resource_record_post_action 05:00:05 INFO - Running post-action listener: set_extra_try_arguments 05:00:05 INFO - ##### 05:00:05 INFO - ##### Finished download-and-extract step (success) 05:00:05 INFO - ##### 05:00:05 INFO - ##### 05:00:05 INFO - ##### Running populate-webroot step. 05:00:05 INFO - ##### 05:00:05 INFO - Running pre-action listener: _resource_record_pre_action 05:00:05 INFO - Running main action method: populate_webroot 05:00:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:00:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:00:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:00:05 INFO - '--tppagecycles', 05:00:05 INFO - '7'], 05:00:05 INFO - 'tests': ['tsvgm']}}, 05:00:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:00:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:00:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:00:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:00:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:00:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['damp', 'tps']}, 05:00:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'tests': ['damp', 'tps']}, 05:00:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['dromaeo_dom']}, 05:00:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:00:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['a11yr', 05:00:05 INFO - 'ts_paint', 05:00:05 INFO - 'tpaint', 05:00:05 INFO - 'sessionrestore', 05:00:05 INFO - 'sessionrestore_no_auto_restore', 05:00:05 INFO - 'tabpaint']}, 05:00:05 INFO - 'other-e10s': {'tests': ['a11yr', 05:00:05 INFO - 'ts_paint', 05:00:05 INFO - 'tpaint', 05:00:05 INFO - 'sessionrestore', 05:00:05 INFO - 'sessionrestore_no_auto_restore', 05:00:05 INFO - 'tabpaint']}, 05:00:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:00:05 INFO - 'ts_paint', 05:00:05 INFO - 'tpaint', 05:00:05 INFO - 'sessionrestore', 05:00:05 INFO - 'sessionrestore_no_auto_restore', 05:00:05 INFO - 'tabpaint']}, 05:00:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:00:05 INFO - 'ts_paint', 05:00:05 INFO - 'tpaint', 05:00:05 INFO - 'sessionrestore', 05:00:05 INFO - 'sessionrestore_no_auto_restore', 05:00:05 INFO - 'tabpaint']}, 05:00:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['a11yr', 05:00:05 INFO - 'ts_paint', 05:00:05 INFO - 'tpaint', 05:00:05 INFO - 'sessionrestore', 05:00:05 INFO - 'sessionrestore_no_auto_restore', 05:00:05 INFO - 'tabpaint']}, 05:00:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['a11yr', 05:00:05 INFO - 'ts_paint', 05:00:05 INFO - 'tpaint', 05:00:05 INFO - 'sessionrestore', 05:00:05 INFO - 'sessionrestore_no_auto_restore', 05:00:05 INFO - 'tabpaint']}, 05:00:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['tsvgx', 05:00:05 INFO - 'tsvgr_opacity', 05:00:05 INFO - 'tart', 05:00:05 INFO - 'tscrollx', 05:00:05 INFO - 'cart']}, 05:00:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:00:05 INFO - 'tsvgr_opacity', 05:00:05 INFO - 'tart', 05:00:05 INFO - 'tscrollx', 05:00:05 INFO - 'cart']}, 05:00:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'talos_options': ['--disable-e10s'], 05:00:05 INFO - 'tests': ['tp5o']}, 05:00:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'tests': ['tp5o']}, 05:00:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'talos_options': ['--disable-e10s', 05:00:05 INFO - '--xperf_path', 05:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:05 INFO - 'tests': ['tp5n']}, 05:00:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:00:05 INFO - 'talos_options': ['--xperf_path', 05:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:00:05 INFO - 'tests': ['tp5n']}}, 05:00:05 INFO - 'talos.zip': {'path': '', 05:00:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:00:05 INFO - Running post-action listener: _resource_record_post_action 05:00:05 INFO - ##### 05:00:05 INFO - ##### Finished populate-webroot step (success) 05:00:05 INFO - ##### 05:00:05 INFO - ##### 05:00:05 INFO - ##### Running create-virtualenv step. 05:00:05 INFO - ##### 05:00:05 INFO - Running pre-action listener: _resource_record_pre_action 05:00:05 INFO - Running main action method: create_virtualenv 05:00:05 INFO - Creating virtualenv /builds/slave/test/build/venv 05:00:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:00:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:00:06 INFO - Using real prefix '/tools/python27' 05:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:00:09 INFO - Installing pip.................done. 05:00:09 INFO - Return code: 0 05:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:09 INFO - 'HOME': '/Users/cltbld', 05:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:09 INFO - 'LOGNAME': 'cltbld', 05:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:09 INFO - 'MOZ_NO_REMOTE': '1', 05:00:09 INFO - 'NO_EM_RESTART': '1', 05:00:09 INFO - 'PAGER': '/bin/cat', 05:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:09 INFO - 'PWD': '/builds/slave/test', 05:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:09 INFO - 'SHELL': '/bin/bash', 05:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:09 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:09 INFO - 'USER': 'cltbld', 05:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:09 INFO - 'XPC_FLAGS': '0x0', 05:00:09 INFO - 'XPC_SERVICE_NAME': '0', 05:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:00:09 INFO - Cleaning up... 05:00:09 INFO - Return code: 0 05:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:09 INFO - 'HOME': '/Users/cltbld', 05:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:09 INFO - 'LOGNAME': 'cltbld', 05:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:09 INFO - 'MOZ_NO_REMOTE': '1', 05:00:09 INFO - 'NO_EM_RESTART': '1', 05:00:09 INFO - 'PAGER': '/bin/cat', 05:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:09 INFO - 'PWD': '/builds/slave/test', 05:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:09 INFO - 'SHELL': '/bin/bash', 05:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:09 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:09 INFO - 'USER': 'cltbld', 05:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:09 INFO - 'XPC_FLAGS': '0x0', 05:00:09 INFO - 'XPC_SERVICE_NAME': '0', 05:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:10 INFO - Downloading/unpacking psutil>=3.1.1 05:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:12 INFO - Installing collected packages: psutil 05:00:12 INFO - Running setup.py install for psutil 05:00:12 INFO - building 'psutil._psutil_osx' extension 05:00:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:00:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:00:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:00:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:00:13 INFO - building 'psutil._psutil_posix' extension 05:00:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:00:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:00:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:00:13 INFO - ^ 05:00:13 INFO - 1 warning generated. 05:00:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:00:13 INFO - Successfully installed psutil 05:00:13 INFO - Cleaning up... 05:00:13 INFO - Return code: 0 05:00:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:00:13 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')]} 05:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:14 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:14 INFO - 'HOME': '/Users/cltbld', 05:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:14 INFO - 'LOGNAME': 'cltbld', 05:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:14 INFO - 'MOZ_NO_REMOTE': '1', 05:00:14 INFO - 'NO_EM_RESTART': '1', 05:00:14 INFO - 'PAGER': '/bin/cat', 05:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:14 INFO - 'PWD': '/builds/slave/test', 05:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:14 INFO - 'SHELL': '/bin/bash', 05:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:14 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:14 INFO - 'USER': 'cltbld', 05:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:14 INFO - 'XPC_FLAGS': '0x0', 05:00:14 INFO - 'XPC_SERVICE_NAME': '0', 05:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00: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 05:00:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:00:17 INFO - Installing collected packages: mozsystemmonitor 05:00:17 INFO - Running setup.py install for mozsystemmonitor 05:00:17 INFO - Successfully installed mozsystemmonitor 05:00:17 INFO - Cleaning up... 05:00:17 INFO - Return code: 0 05:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:00:17 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')]} 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 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')]} 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:17 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:17 INFO - 'HOME': '/Users/cltbld', 05:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:17 INFO - 'LOGNAME': 'cltbld', 05:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:17 INFO - 'MOZ_NO_REMOTE': '1', 05:00:17 INFO - 'NO_EM_RESTART': '1', 05:00:17 INFO - 'PAGER': '/bin/cat', 05:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:17 INFO - 'PWD': '/builds/slave/test', 05:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:17 INFO - 'SHELL': '/bin/bash', 05:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:17 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:17 INFO - 'USER': 'cltbld', 05:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:17 INFO - 'XPC_FLAGS': '0x0', 05:00:17 INFO - 'XPC_SERVICE_NAME': '0', 05:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 05:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:21 INFO - Downloading docopt-0.6.1.tar.gz 05:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:00:21 INFO - Running setup.py install for blobuploader 05:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:00:21 INFO - Running setup.py install for requests 05:00:21 INFO - Running setup.py install for docopt 05:00:22 INFO - Successfully installed blobuploader requests docopt 05:00:22 INFO - Cleaning up... 05:00:22 INFO - Return code: 0 05:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:22 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')]} 05:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:22 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')]} 05:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:22 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:22 INFO - 'HOME': '/Users/cltbld', 05:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:22 INFO - 'LOGNAME': 'cltbld', 05:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:22 INFO - 'MOZ_NO_REMOTE': '1', 05:00:22 INFO - 'NO_EM_RESTART': '1', 05:00:22 INFO - 'PAGER': '/bin/cat', 05:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:22 INFO - 'PWD': '/builds/slave/test', 05:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:22 INFO - 'SHELL': '/bin/bash', 05:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:22 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:22 INFO - 'USER': 'cltbld', 05:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:22 INFO - 'XPC_FLAGS': '0x0', 05:00:22 INFO - 'XPC_SERVICE_NAME': '0', 05:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-yjhR3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-SQDMnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-ZkV0hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-YKHwFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-JrYsr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:22 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-GwftnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-iSRlFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-kgun4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-3OhcTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-L5zgkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-QX52df-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-bIH8lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-pSo8eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Y2qxmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:23 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-J4pvll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-o8Sm31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:24 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-ZdFb8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:24 INFO - Running setup.py install for manifestparser 05:00:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Running setup.py install for mozcrash 05:00:24 INFO - Running setup.py install for mozdebug 05:00:24 INFO - Running setup.py install for mozdevice 05:00:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:24 INFO - Running setup.py install for mozfile 05:00:24 INFO - Running setup.py install for mozhttpd 05:00:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for mozinfo 05:00:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for mozInstall 05:00:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for mozleak 05:00:25 INFO - Running setup.py install for mozlog 05:00:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for moznetwork 05:00:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:25 INFO - Running setup.py install for mozprocess 05:00:25 INFO - Running setup.py install for mozprofile 05:00:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for mozrunner 05:00:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Running setup.py install for mozscreenshot 05:00:26 INFO - Running setup.py install for moztest 05:00:26 INFO - Running setup.py install for mozversion 05:00:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:26 INFO - Cleaning up... 05:00:26 INFO - Return code: 0 05:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:26 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')]} 05:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:26 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')]} 05:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:26 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:26 INFO - 'HOME': '/Users/cltbld', 05:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:26 INFO - 'LOGNAME': 'cltbld', 05:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:26 INFO - 'MOZ_NO_REMOTE': '1', 05:00:26 INFO - 'NO_EM_RESTART': '1', 05:00:26 INFO - 'PAGER': '/bin/cat', 05:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:26 INFO - 'PWD': '/builds/slave/test', 05:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:26 INFO - 'SHELL': '/bin/bash', 05:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:26 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:26 INFO - 'USER': 'cltbld', 05:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:26 INFO - 'XPC_FLAGS': '0x0', 05:00:26 INFO - 'XPC_SERVICE_NAME': '0', 05:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-E_ot6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-7W2rug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-RHivKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-mfbFYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-agHIT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-CU1uRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-_bh0FM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-aS5P8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-SPCvNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:27 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-wUfc9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-6O__6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-vFe0f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-QpezEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-pcvLjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:28 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)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-GK30eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Jz0Kan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:28 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-59WkAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:00:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:31 INFO - Downloading blessings-1.6.tar.gz 05:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:31 INFO - Installing collected packages: blessings 05:00:31 INFO - Running setup.py install for blessings 05:00:31 INFO - Successfully installed blessings 05:00:31 INFO - Cleaning up... 05:00:31 INFO - Return code: 0 05:00:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:32 INFO - Reading from file tmpfile_stdout 05:00:32 INFO - Current package versions: 05:00:32 INFO - blessings == 1.6 05:00:32 INFO - blobuploader == 1.2.4 05:00:32 INFO - docopt == 0.6.1 05:00:32 INFO - manifestparser == 1.1 05:00:32 INFO - mozInstall == 1.12 05:00:32 INFO - mozcrash == 0.17 05:00:32 INFO - mozdebug == 0.1 05:00:32 INFO - mozdevice == 0.48 05:00:32 INFO - mozfile == 1.2 05:00:32 INFO - mozhttpd == 0.7 05:00:32 INFO - mozinfo == 0.9 05:00:32 INFO - mozleak == 0.1 05:00:32 INFO - mozlog == 3.2 05:00:32 INFO - moznetwork == 0.27 05:00:32 INFO - mozprocess == 0.22 05:00:32 INFO - mozprofile == 0.28 05:00:32 INFO - mozrunner == 6.11 05:00:32 INFO - mozscreenshot == 0.1 05:00:32 INFO - mozsystemmonitor == 0.3 05:00:32 INFO - moztest == 0.7 05:00:32 INFO - mozversion == 1.4 05:00:32 INFO - psutil == 3.1.1 05:00:32 INFO - requests == 1.2.3 05:00:32 INFO - wsgiref == 0.1.2 05:00:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:32 INFO - 'HOME': '/Users/cltbld', 05:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:32 INFO - 'LOGNAME': 'cltbld', 05:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:32 INFO - 'MOZ_NO_REMOTE': '1', 05:00:32 INFO - 'NO_EM_RESTART': '1', 05:00:32 INFO - 'PAGER': '/bin/cat', 05:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:32 INFO - 'PWD': '/builds/slave/test', 05:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:32 INFO - 'SHELL': '/bin/bash', 05:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:32 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:32 INFO - 'USER': 'cltbld', 05:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:32 INFO - 'XPC_FLAGS': '0x0', 05:00:32 INFO - 'XPC_SERVICE_NAME': '0', 05:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:00:32 INFO - Cleaning up... 05:00:32 INFO - Return code: 0 05:00:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:32 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:32 INFO - 'HOME': '/Users/cltbld', 05:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:32 INFO - 'LOGNAME': 'cltbld', 05:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:32 INFO - 'MOZ_NO_REMOTE': '1', 05:00:32 INFO - 'NO_EM_RESTART': '1', 05:00:32 INFO - 'PAGER': '/bin/cat', 05:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:32 INFO - 'PWD': '/builds/slave/test', 05:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:32 INFO - 'SHELL': '/bin/bash', 05:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:32 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:32 INFO - 'USER': 'cltbld', 05:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:32 INFO - 'XPC_FLAGS': '0x0', 05:00:32 INFO - 'XPC_SERVICE_NAME': '0', 05:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:32 INFO - Downloading/unpacking jsonschema 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:00:35 INFO - Downloading/unpacking functools32 (from jsonschema) 05:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:00:35 INFO - warning: no files found matching '*.txt' 05:00:35 INFO - no previously-included directories found matching 'build' 05:00:35 INFO - no previously-included directories found matching 'dist' 05:00:35 INFO - no previously-included directories found matching '.git*' 05:00:35 INFO - Installing collected packages: jsonschema, functools32 05:00:35 INFO - Running setup.py install for functools32 05:00:35 INFO - warning: no files found matching '*.txt' 05:00:35 INFO - no previously-included directories found matching 'build' 05:00:35 INFO - no previously-included directories found matching 'dist' 05:00:35 INFO - no previously-included directories found matching '.git*' 05:00:35 INFO - Successfully installed jsonschema functools32 05:00:35 INFO - Cleaning up... 05:00:35 INFO - Return code: 0 05:00:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:35 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 0x10e014a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e01a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e024682d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, '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.0AYo5WJDEF/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.gswTJgJtLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:00:35 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 05:00:35 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 05:00:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:00:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:35 INFO - 'HOME': '/Users/cltbld', 05:00:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:35 INFO - 'LOGNAME': 'cltbld', 05:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:35 INFO - 'MOZ_NO_REMOTE': '1', 05:00:35 INFO - 'NO_EM_RESTART': '1', 05:00:35 INFO - 'PAGER': '/bin/cat', 05:00:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:35 INFO - 'PWD': '/builds/slave/test', 05:00:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:35 INFO - 'SHELL': '/bin/bash', 05:00:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:00:35 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:00:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:35 INFO - 'USER': 'cltbld', 05:00:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:35 INFO - 'XPC_FLAGS': '0x0', 05:00:35 INFO - 'XPC_SERVICE_NAME': '0', 05:00:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:36 INFO - Downloading/unpacking flake8 05:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:00:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:00:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:00:40 INFO - no previously-included directories found matching 'docs/_build' 05:00:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:00:40 INFO - Running setup.py install for pep8 05:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:00:40 INFO - no previously-included directories found matching 'docs/_build' 05:00:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:00:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:00:40 INFO - Cleaning up... 05:00:40 INFO - Return code: 0 05:00:40 INFO - Running post-action listener: _resource_record_post_action 05:00:40 INFO - Running post-action listener: _start_resource_monitoring 05:00:40 INFO - Starting resource monitoring. 05:00:40 INFO - ##### 05:00:40 INFO - ##### Finished create-virtualenv step (success) 05:00:40 INFO - ##### 05:00:40 INFO - ##### 05:00:40 INFO - ##### Running install step. 05:00:40 INFO - ##### 05:00:40 INFO - Running pre-action listener: _resource_record_pre_action 05:00:40 INFO - Running main action method: install 05:00:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:40 INFO - Reading from file tmpfile_stdout 05:00:40 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:40 INFO - Reading from file tmpfile_stdout 05:00:40 INFO - Output received: 05:00:40 INFO - Usage: mozinstall [options] installer 05:00:40 INFO - Options: 05:00:40 INFO - -h, --help show this help message and exit 05:00:40 INFO - -d DEST, --destination=DEST 05:00:40 INFO - Directory to install application into. [default: 05:00:40 INFO - "/builds/slave/test"] 05:00:40 INFO - --app=APP Application being installed. [default: firefox] 05:00:40 INFO - mkdir: /builds/slave/test/build/application 05:00:40 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'] 05:00:40 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 05:01:14 INFO - Reading from file tmpfile_stdout 05:01:14 INFO - Output received: 05:01:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:01:14 INFO - Running post-action listener: _resource_record_post_action 05:01:14 INFO - ##### 05:01:14 INFO - ##### Finished install step (success) 05:01:14 INFO - ##### 05:01:14 INFO - ##### 05:01:14 INFO - ##### Running run-tests step. 05:01:14 INFO - ##### 05:01:14 INFO - Running pre-action listener: _resource_record_pre_action 05:01:14 INFO - Running main action method: run_tests 05:01:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:14 INFO - 2016-05-31 05:01:14.985 screenresolution[1647:8097] starting screenresolution argv=screenresolution get 05:01:14 INFO - 2016-05-31 05:01:14.988 screenresolution[1647:8097] Display 0: 1600x1200x32@60 05:01:14 INFO - 2016-05-31 05:01:14.995 screenresolution[1648:8099] starting screenresolution argv=screenresolution list 05:01:14 INFO - Available Modes on Display 0 05:01:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:15 INFO - Intel Iris: 05:01:15 INFO - Chipset Model: Intel Iris 05:01:15 INFO - Type: GPU 05:01:15 INFO - Bus: Built-In 05:01:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:15 INFO - Vendor: Intel (0x8086) 05:01:15 INFO - Device ID: 0x0a2e 05:01:15 INFO - Revision ID: 0x0009 05:01:15 INFO - Displays: 05:01:15 INFO - Display: 05:01:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:15 INFO - Display Serial Number: 1600x1200 60 05:01:15 INFO - Main Display: Yes 05:01:15 INFO - Mirror: Off 05:01:15 INFO - Online: Yes 05:01:15 INFO - Rotation: Supported 05:01:15 INFO - Return code: 0 05:01:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:01:15 INFO - Python 2.7.3 05:01:15 INFO - Return code: 0 05:01:15 INFO - grabbing minidump binary from tooltool 05:01: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')]} 05:01:15 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 0x10deb8d30>, '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 0x10dcdcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10deafb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:01:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:01:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:01:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:01:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:01:15 INFO - Return code: 0 05:01:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:01:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:01:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:01:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:01:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:01:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:01:16 INFO - Return code: 0 05:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0262 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0AYo5WJDEF/Render', 05:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:16 INFO - 'HOME': '/Users/cltbld', 05:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:16 INFO - 'LOGNAME': 'cltbld', 05:01:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:01:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:16 INFO - 'MOZ_NO_REMOTE': '1', 05:01:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:01:16 INFO - 'NO_EM_RESTART': '1', 05:01:16 INFO - 'PAGER': '/bin/cat', 05:01:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:16 INFO - 'PWD': '/builds/slave/test', 05:01:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:16 INFO - 'SHELL': '/bin/bash', 05:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gswTJgJtLl/Listeners', 05:01:16 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:16 INFO - 'USER': 'cltbld', 05:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:16 INFO - 'XPC_FLAGS': '0x0', 05:01:16 INFO - 'XPC_SERVICE_NAME': '0', 05:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:01:16 INFO - mozversion application_buildid: 20160531025850 05:01:16 INFO - mozversion application_changeset: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d 05:01:16 INFO - mozversion application_display_name: Nightly 05:01:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:01:16 INFO - mozversion application_name: Firefox 05:01:16 INFO - mozversion application_remotingname: firefox 05:01:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:01:16 INFO - mozversion application_vendor: Mozilla 05:01:16 INFO - mozversion application_version: 49.0a1 05:01:16 INFO - mozversion platform_buildid: 20160531025850 05:01:16 INFO - mozversion platform_changeset: dc4d7f68030e9edd22ad0bb0bd2244d047dd767d 05:01:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:01:16 INFO - mozversion platform_version: 49.0a1 05:01:16 INFO - using testdate: 1464696076 05:01:16 INFO - actual date: 1464696076 05:01:16 INFO - starting webserver on 'localhost:49274' 05:01:16 INFO - SUITE-START | Running 2 tests 05:01:16 INFO - TEST-START | dromaeo_css 05:01:16 INFO - operating with platform_type : mac_ 05:01:16 INFO - Initialising browser for dromaeo_css test... 05:01:16 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpciXv_d/profile http://localhost:49274/getInfo.html) 05:01:19 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 05:01:19 INFO - PROCESS | 1666 | colorDepth:24 05:01:19 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 05:01:19 INFO - PROCESS | 1666 | __metrics 05:01:19 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:01:19 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:01:19 INFO - TEST-INFO | 1666: exit 0 05:01:19 INFO - Browser initialized. 05:01:19 INFO - Running cycle 1/1 for dromaeo_css test... 05:01:19 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpciXv_d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:31 INFO - PROCESS | 1669 | RSS: Main: 241475584 05:01:31 INFO - PROCESS | 1669 | 05:02:23 INFO - PROCESS | 1669 | 1464696143141 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:04:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 05:04:35 INFO - PROCESS | 1669 | RSS: Main: 383864832 05:04:35 INFO - PROCESS | 1669 | 05:07:38 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 05:07:39 INFO - PROCESS | 1669 | RSS: Main: 380125184 05:07:39 INFO - PROCESS | 1669 | 05:10:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 05:10:41 INFO - PROCESS | 1669 | RSS: Main: 373362688 05:10:41 INFO - PROCESS | 1669 | 05:13:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 05:13:44 INFO - PROCESS | 1669 | RSS: Main: 372858880 05:13:44 INFO - PROCESS | 1669 | 05:16:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 05:16:48 INFO - PROCESS | 1669 | RSS: Main: 343773184 05:16:48 INFO - PROCESS | 1669 | 05:19:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 05:19:52 INFO - PROCESS | 1669 | RSS: Main: 380022784 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | __start_tp_report 05:19:52 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:19:52 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:19:52 INFO - PROCESS | 1669 | |i|pagename|runs| 05:19:52 INFO - PROCESS | 1669 | |0;dojo.html;3468.3063387322536;3487.511748355231;3499.7200223982086;3512.3502152101846;3557.1107223194203;5544.0575102232615;5578.107502799553;5673.943260567393;5751.051076572366;5769.740361683724;5675.659460432377;5751.338596061454;5814.244148260731;5821.854453638661;5839.7664093436215;5133.614978876581;5142.768575414097;5209.973950130244;5218.060749065166;5304.655197412156;5249.737513124348;5316.787328506855;5320.574354051686;5321.707306098163;5351.304330437038;5110.923336149942;5148.3564554430695;5169.1212493875755;5172.146595811687;5210.869728256805;5187.688738675653;5194.039102766001;5198.896022079537;5199.376074871002;5221;5217.060929032773;5227.2159176123505;5232.764525596319;5233.031889100528;5269.789208431658;5590.664560126405;5641.294838145232;5739.311282646067;5758.539316854642;5814.883702325972;6235.812925612239;6292.2134733158355;6310.779122730728;6323.051542268697;6336.96831515844;5248.448912864133;6244.750209991595;6345.460635845959;6345.587536810092;6355.555111142222;5710.543156547466;5756.42435756421;5773.797917072966;5879.382664820175;5886.264216972879;6250.374962503804;6304.401081897213;6307.211598550181;6309.873802523924;6318.052292156121;6181.690915454209;6196.3803619638575;6199.318075011745;6209.720562574695;6210.068489726577;6140.534923917717;6185;6195.597286176474;6232.968835155795;6277.811665650038;6146.354632763585;6153.446189842935;6181.474574661114;6198.535109866778;6311.526635502264;6315.273743519462;6322.304546499881;6332.1135041094285;6333.809985700436;6363.204599425072;4903;4955.900881982412;4956.553910148032;4957.380327459067;4990.700557966534;4324.675649326313;4407.140607581555;4438.090191510732;4445.022095139064;4467.486239082547;5715;5722.971385143131;5723.313202415654;5727.026405511073;5732.971335143297;6243.0635404688755;6249.75000999964;6278.905816412757;6319.715612797435;6345.206849143857;5782.2194003808945;5795.072788353843;5808.477237048686;5816.651000939957;5832.562557808182;5395.352557693023;5403.324584426947;5416.160495123183;5442.537384322298;5451.700156491352;6223.097650840603;6289.8742025159245;6302.684865756693;6337.429631333202;6427.421532062137;6252.843678908063;6306.211723534559;6310.274318453253;6330.778422755181;6336.904946425715;6230.15892854477;6371.681415929278;6375.266844312963;6375.776847810304;6383.553151279366;6144.603604594123;6153.600015998945;6218.533609979179;6273.592216505913;6279.843003924939;6312.905306420315;6313.179286692701;6321.936780632135;6324.620522768465;6329.335419780857;6018.9398106020135;6107.144999699956;6191.690415479298;6193.102000210034;6211.0372892201785;5955.136505206808;6081.300650425081;6104.938950610437;6106.603070800384;6141;5962.821115366546;6017.338092809699;6028.33688294296;6057.848553786014;6064.514838812973;6179.16581261524;6199.721012554356;6199.783007594712;6200.503959683124;6212.378762123752;5860.120981852756;5909.349971503045;5966.105084237399;5998;6016.067509536029 05:19:52 INFO - PROCESS | 1669 | |1;ext.html;497.5273490184326;497.6342388344568;501.2556353814585;534.0360649028504;536.3215532351576;31279.530807037914;41470.37794433082;42555.57444425555;42643.72068837932;42647;43939.78030109845;44363;44460.55539444605;44483.55516444835;44564;8654.177853103945;9497.192738617208;9702.66040688576;9707.320487565874;9720.75698107546;8666.350023748213;11507.482163302671;11792.410379481034;11835.230710003822;12075.93962030193;11808.822867656992;11898.643040708792;11909.821352679717;11914.761704765899;11916.821247681291;8659.437136586102;8869.290456763443;8894.688685896028;11531.019863311629;11566.47950842214;8756.124387561256;11324.49039793203;11733.296002239893;11734.711731212417;12002.51989920402;41301.586984130074;41649.75050748482;41662.79168604168;41709.79145104255;42037.579624203674;3453.4819777033395;3494.965050349515;3925.548561915359;4209.158168366308;4233.153369326116;31118.37763244745;32031.199220019455;32402.837985810154;32427.351452971045;32559.18602034968;2718.7009428962797;2893.7829662775375;3289.4572395554706;3330.7668463207583;3361.7310615150973;390309;393046;393111;393480;393522.03238983906;60740;60921.69539152276;61103;61230.693846530485;61247.38752612417;64224.67887660532;64238.715225695225;64243.67878160673;64386;64576.67711661506;16424.1787910604;16691.41580044717;16704.74942875858;16719.749203761952;16726.414575489798;11734.295942243492;11880.227785193934;12818.294993775246;12904.677383065498;12967;16074.678506429806;16079.035257884565;16115.758263626056;16131.919340403458;16254.51236462908;5725.914111288334;6068.908966365508;6360.0777887206095;6399;6414.294427613052;769.8267889724767;866.3026263857529;912.370464379576;1027.1012863744224;1084.1976937066672;29441.705582944323;29733.256668583457;29766.25584360365;30485.542716859265;30753.538696919564;921.1065266691298;1008.7326858382535;1080.8486811846349;1104.5526561742506;1105.2539535813294;13515.526956556474;14837.332320045409;15456.768148477782;15601.375944962301;16007.679846403007;631736;636875;637481;638662.8066859728;639154;59088.40911590829;59195;59293.703531482926;59332.70333648304;59502;1288.8260084888607;1407.3737186951707;1434.63416828708;1444.097439100562;1450.6445920749484;28609.99865004715;30852.845735772076;31040;31063.844680776452;31806.204844879063;50198;50505;50743;50869;51119.488805111476;533685;534317;534872;536081;536223;34124.82937585396;40695.98260043404;41930.790346048074;42027.7898610505;43492.782536087114;48909;49009;49803.75098124486;49927.500724992286;50302;38426.42360364653;39563.20873582455;39868.2026359477;40232.79301620956;40575;33112.50331244985;38157.80921095377;38531.42202866903;39154.80422597869;39870;34081;37564.248715025;38713.22573548457;38877;38882.61117388903;28595.142145735663;32686.673133268363;33385.165370864954;33663.495047573815;34527.65472345244;24911.878965446234;25181.622275666246;25370.98516059337;25847.966081356535;26321.21036368912 05:19:52 INFO - PROCESS | 1669 | |2;jquery.html;383.5608228578278;385.6779589043149;388.79005337117957;405.2280405826897;411.2679430613509;12888.935555322221;20046.69929951048;21459;21484.248051318205;21516.139354425803;20160.395188144343;20417.08123134459;20955;21097.367078987616;21146.365609031753;4934.5312195341385;5267.789288428458;6041.9697901510435;6052.122442245872;6066.6663333516635;5807.506361959238;5950.196723442323;6014.639121652693;6064.514838812885;6066.514678825705;5640.915386269209;5930.466258036776;5942.524598032169;5950.613210141349;5954.791582294621;4937.80248790048;5923.466887980081;5935.88128237435;5937.257842769675;5990.400959904018;5098.821541246076;5255.763490642892;5281.577473802086;5287.894242115175;5288.947110528922;23592.410189745224;24995.12517061894;25365.492690146093;25900.481990360277;26030.73969260302;1935.4870959195684;2189.2337681811396;2291.6218823894037;2349.0721165139676;2880.8127471714483;9141.908580914172;10237.283390162691;10568.678915135608;13500;14223.502177423845;1597.752348386002;1847.7228415737748;1896.9620607587772;1965.7837637859825;1966.6754985427388;104700.47649761778;108626.91373086246;108636;114548;115838.99999999916;11405.600803971822;11488;11644.41777911101;11655.825162622568;11766.52933882635;14767.409303627735;14835.629109272355;14880.404783808526;14890.627734306512;14923.104613722995;8401.747947561584;8582.356323275779;8647.221750042527;8689.6524139035;8695.217430431165;8487.448315859448;8487.618057187441;8489.278411335106;8621.655133794704;8636.438631489058;4502.662300327489;4573.314002899525;4586.724796512153;4615.861524154281;4637.652176086807;1640.2045008171028;1650.5626009107596;1794.6590147871862;1886.990565047166;1956.6380219659409;770.9884351734728;833.8165603567205;979.3536266064364;994.7165057958447;1004.7990401919645;20549.38351849447;21219.78780212178;21261;21263.68104478434;21328;795.3756301303449;820.9876851847227;976.0727309056425;1009.6112996496255;1049.9422531760674;9034.86447703285;10712.517936692868;10754;11015.724606884733;11240.662780116447;246773;247409;247513;639378;644851;97506.53740193903;107646.46176769222;144564;149890;151543;1010.2978429991233;1011.4891979550428;1022.1975749037119;1071.8285074388216;1077.3966578715945;18696.065196739837;19030.904845476238;19125.426237212905;19130.426087217405;19203.580938297553;55394;55461.63036577572;56945.43054569401;57746;57746.133807993756;63834.04248936177;74221;76913;79080.81378779208;88709;8180.672773089248;8229.79425514367;8294.792630184053;8356.415050946376;8364.16358364183;12740.74518509606;12952.935235323763;13009.804852927024;13219;13291.136076155019;5585.469380408855;9029.638814447611;9098.818023639622;11124.499397527292;11481.540738370371;11034.889651103385;11167.832482512606;11298.378589177673;11335.603253886075;11484.425778711198;11062.893710628872;11481.885181148082;11509.712257193638;11717.296962182296;11726.413679315829;7079.115110611174;7395.112586489656;7419.962900185465;8363;8388.622511987098;8123.94388729485;10132.189424846141;11217.831732523855;11329.263597866113;11379.146564007568 05:19:52 INFO - PROCESS | 1669 | |3;mootools.html;1366.2349084512668;1543.5678010157153;1568.6392129810142;1822.6719190545705;1998.9400317990455;7897.2497612726775;8687.044425113232;8913.286937045037;8970.2823774098;9031.909680903189;8633.136686331363;8876.911230887688;8881.777955551115;8888.866667;8891.644334226623;1742.860571154311;2172.196287373674;2175.4235127691095;2370.8814559272055;2413.263954493879;1958.9804101959012;2038.7247721557549;2197.4067001909475;2225.2100504320974;2437.4881274932195;2399.7240317363553;2405.2062819269686;2405.338531903723;2406.434487895352;2406.5427568761966;2335.4978679583887;2377.7741114594087;2384.7972922301624;2386.2721869829693;2386.5107652931106;2131.8827464489445;2168.8915554222385;2183.875304218329;2398.0647547456506;2414.6498757680215;8797.912020879838;8875.28997680184;8877.467351958838;8883.955580222058;8886;5229.137192363255;5402.297701298845;5409.404965453797;5412.377576578665;5412.91880621791;3375.3418083473734;3484.4250698634696;3558.19940513385;3563.1448452371246;3568.946465803015;935.1396715022128;954.5227386306847;1020.7703266764993;1020.775429405537;1050.6217761605815;11858.644240672707;11960.102992275613;11960.940195299056;11971.461284242143;11974.58088966882;4712.512330667;4780.95219047805;4849.224124140192;4861.6353773467135;4871.878203044952;10062.849057264148;10120.089191972758;10121.544530496145;10136.847947280648;10138.898611013943;5316.069687804618;5392.975334686397;5401.027814993339;5402.567794576425;5408.080626293539;4428.446444194476;4776.617870570346;4873.731944743002;4934.383202099738;4943.085529177114;3728.5898551159353;3937.547182074098;3975.1453437511072;4043.534993475788;4054.7769872656695;1273.611548477723;1285;1285.7299967007034;1297.2605614799475;1297.863724308953;886.8581027035643;907.1518130547959;946.6402766948515;967.7612655800586;989.5794287427815;5994.790182343597;6033.276006879202;6149.169862068653;6268.404501572343;6313.179286692701;914.9359544831931;946.3564775952418;949.3307218411004;955.1546881010356;970.0784254957678;4038.414429907647;4120.052387950728;4126.42230087788;4178.561251068655;4191.287481128215;10696.197785166143;10986.230963832455;11072.944635276772;11079.390633515073;11099.944500277446;10947.959943805326;11062.39156846365;11071.446427678584;11073.114150867912;11074.335539867634;7921.128675845653;8286.41995060352;8297.875531867026;8404.20160084791;8443.493390396596;5412.771036403296;6001.189839371629;6127.969360153171;6246.5002799775;6253.3121356651645;10033.949830250802;10326.432046237229;10331.32846364984;10336.586536538454;10339.948300258451;9808.16630586408;10308.587656493657;10342.224333174892;10410.47947602632;10426.374417534973;6004.309504406874;7069.363757261871;7295.489315748059;7745.186755390603;7771.37828973692;8263.752087437386;8510.872336915076;8510.957445212734;8626.870596941177;8632.654693812177;6291.370862913672;7541.924580754122;7555.055618047744;7680.846383072419;7691.961585185928;7410.555366678017;7441.776746697607;7450.515716478411;7479.925200747923;7509.774706758806;6170.79669464468;7107.360337569644;7113.8577228454105;7169.103862017248;7174.174969878322;4969.229769385751;5006.524380183877;5027.723475208899;5102.948970510248;5175.301334319819;4215.915681686411;5074.467180945948;5183.637145399785;5233.764480598306;5338.412774594869 05:19:52 INFO - PROCESS | 1669 | |4;prototype.html;261.56971781419566;297.3948015787872;311.9266972261518;315.9289159939013;316.3246468789134;5200.349956255468;5331.093714068608;5346.984073026122;5359.338605032438;5366;3744.1949980754134;3867.9226415471744;4309.0735491869245;5092.28707980883;5291.28567643368;3998.340273854825;4002.8398864045403;4052.9594704053025;4068.9389659155063;4071.7149799514045;3314.8342582870882;3441.9655803441897;3587.8385472653795;4037.3540233562635;4118.423420721101;3787.8068408451386;3894.824732887013;3928.489296391465;4008.378701301303;4061.57353477885;3526.876559320437;4029.254587901233;4041.5150181978056;4059.939100913489;4065.1869626074904;2896.333843216072;3082.106189205128;3302.6036875574846;3485.268093700326;3521.9471707924404;4590.517995610447;4960.6527543072;4971.9863455300665;5132.589392848563;5144.845654630367;4263.168682106991;4628.490866004737;4640.930386044212;4641.187792136363;4675.929861052087;4961.826336078256;5532.419095994943;5568.303962004749;5572.08060669993;5619.269494965669;3655.6161603031564;3876.5348158220913;3908.062065104383;3922.8623699127506;3936.323300155893;5363.624546281801;5841.415858414167;5841.591088623841;5933.673647949361;5958.553108516879;3657.5245218122004;3692.409214525663;3707.1844194277214;3790.2798468290935;3960.940585891214;3460.2733425980323;3921.9998900280248;3937.488126543531;4010.135287091458;4023.637872591481;3907.765534067965;4162.5629308922125;4430.955690443118;4598.333241679938;4624.768761561976;5564.831385409109;5604.271444712243;5613.663180209201;5644.996026410565;5647.915281270784;1289.864918871387;1326.1446367093172;1326.383231797219;1345.1391109689807;1379.6343968848264;672.0477014273544;800.9959950200212;827.5862068965517;836.4730219961385;888.9554773141533;4528.071745292207;4781.665283430196;5065.341505604248;5235.554977826928;5262.368515778131;5305.60207984395;5525.668459892419;5659.971700141473;5710.229119068956;5735.824156048;4781.856544303677;4910.680805747615;5087.949120508822;5167.612429067835;5189.662671926313;2594.5200137974157;2993.476141675198;3010.5183170692585;3015.743661788729;3031.6968303169947;4974.850754477371;5432.261676263637;5656.896905103547;5863.885861686266;5948.464638182584;5041.82353617629;5425.864353391197;5861.325947516005;5866.266716660418;5873.383294754075;5159.922601160985;5190.6107041972;5449.99175152598;5970;5987.892239935626;5269.446708095748;5321.521063104339;5702.287214098238;5721.370649228498;5763.682997435181;4768.260919557474;4803.687760295569;4881.267809828554;5334;5345.904089661688;4953.504649535018;5118.462561430996;5935.614185078129;5936.584439089395;5987.34139244674;3249.5450636911323;3886.70849686269;3919.042411349702;3939.980300098481;3941.073847645858;5258.737063146904;5640.294963129609;5654.943450565606;5683.7442315095095;5751.769929202952;4827.151980331621;5298.284731561344;5469.945300546943;5474.644148130359;5495.780168793203;5228.660137091077;5481.534069603968;5501.807436739695;5511.559075273888;5611.438856114356;5262.263453060405;5472.5348345391085;5478.753456094565;5514.80698175562;5551.9722401387735;4826.517348265145;4995.575376093073;5515.420880807618;5560.831275802521;5583.330000399978;4479.17135329952;5433.429489903661;5476.151196564539;5507.917381239205;5528.695921724344 05:19:52 INFO - PROCESS | 1669 | |5;yui.html;648.7437462202433;681.5263391942595;726.8655298769727;766.6243540665076;840.3361344537816;14301.856981430183;14502.564923052325;14538.70922581549;14573.198474083922;14855.257237138141;13967.650808729786;14036.368363423644;14108.647283817909;14118;14132;682.662082269275;713.3116542536477;872.4765140915432;890.1632465482442;908.5230254116576;880.0759202837028;885.3271513649609;889.750869756466;889.8264838356523;893.9553022348889;942.1332374215738;943.4575119306392;943.8678584998106;945.6831961292941;947.2658689515612;722.1334398721528;765.9425543084235;901.7970956534792;939.4034787909726;942.3026960049548;698.4796326736614;698.6332175607846;699.1295836683311;700.6391708270245;702.1854648607641;14355.210463424508;14391.928040359731;14400;14401.495947641886;14405.92797036008;12930.612081637471;13169.407376668072;13298.86701132986;13365.799513007312;13392.531261405902;10035.648752293708;11729.472173752116;11994.220375675553;12000.399980001052;12567.057470689735;4393.07745373472;4403.141387429452;4435.3125265583885;4594.632429405673;4604.401427814396;76915;77053;77495;77915.61042194752;77965.61017194878;10479.633212837514;10612.310199836986;10628.734281643021;10651.094656954245;10745.902619930595;18983.62032759337;19000.904995474935;19023.809761902205;19044.61910761777;19075.141618627196;7349.412047036224;7355.448341374418;7482.289182527651;7627.923720762721;7966.004249468817;4418.558144185556;4422.800973956179;4470.932936005963;4494.752788596593;4620.006698559826;3184.808911465273;3297.38998285318;3299.340131973615;3299.5710557627353;3304.5869266341706;578.4360248757512;626.7743612299569;631.6778442994106;647.6567419267795;648.0764910003227;469.3757302787285;601.4436646102425;641.4900154377282;645.7191121861911;650.1353200243665;8666.306695464347;9111.271098312252;9155.22180614642;9186.540672966325;9358.531800233415;627.8712863862897;663.4824836627438;686.5468790598181;693.4591019005184;703.6763088979025;4241.321388577874;4337.522872484024;4354.564543545684;4362.447873331563;4501.729896206238;65151.348486515475;84780.57609711912;86220;86350.13649863546;86362.27275454455;61214.38785612176;69795.65102174456;71773.92339114918;72450;75026.62486687531;64730.35269647243;64996.675016626505;65760.06799490057;65875;65877;7151.578580150051;8051.4766540174705;8371.372147089114;8654.567271636519;8757.737267881974;31757;42818.57181428146;42907.570924290354;44064.339034915145;44412.33381499345;55743.04899328504;55996;57328;57356;57447.7127614376;9846.704598862045;10107.191424686154;10130.240231982778;10300.433476158583;10373.325733827278;12226.816597750863;12385.752284954071;12515.687107822378;12758.234505929673;12835.294058826854;11490.425478725863;11842.52629894829;11848.22986505909;11882.405879706153;11962.98314643265;11562.94218528902;11663.59177428786;11886.108541859569;11899.940500297444;12046.277223366626;11152.55389784432;11278.436078196222;11484.483198255932;11666.958282300393;11748.70628234301;8059.5164290142775;9789.265805064506;10073.597056117966;10264.692059237936;10362.740931476774;9108.225800807006;9516.33385662997;9564.569594368131;9802.019798020427;9950.601975920883 05:19:52 INFO - PROCESS | 1669 | __end_tp_report 05:19:52 INFO - PROCESS | 1669 | __start_cc_report 05:19:52 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1444 05:19:52 INFO - PROCESS | 1669 | __end_cc_report 05:19:52 INFO - PROCESS | 1669 | __startTimestamp1464697192160__endTimestamp 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:19:52 INFO - PROCESS | 1669 | Number of tests: 6 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5779.58 Median:6016.07 stddev:636.54 (10.6%) stddev-sans-first:612.13 05:19:52 INFO - PROCESS | 1669 | Values: 3468.3 3487.5 3499.7 3512.4 3557.1 5544.1 5578.1 5673.9 5751.1 5769.7 5675.7 5751.3 5814.2 5821.9 5839.8 5133.6 5142.8 5210.0 5218.1 5304.7 5249.7 5316.8 5320.6 5321.7 5351.3 5110.9 5148.4 5169.1 5172.1 5210.9 5187.7 5194.0 5198.9 5199.4 5221.0 5217.1 5227.2 5232.8 5233.0 5269.8 5590.7 5641.3 5739.3 5758.5 5814.9 6235.8 6292.2 6310.8 6323.1 6337.0 5248.4 6244.8 6345.5 6345.6 6355.6 5710.5 5756.4 5773.8 5879.4 5886.3 6250.4 6304.4 6307.2 6309.9 6318.1 6181.7 6196.4 6199.3 6209.7 6210.1 6140.5 6185.0 6195.6 6233.0 6277.8 6146.4 6153.4 6181.5 6198.5 6311.5 6315.3 6322.3 6332.1 6333.8 6363.2 4903.0 4955.9 4956.6 4957.4 4990.7 4324.7 4407.1 4438.1 4445.0 4467.5 5715.0 5723.0 5723.3 5727.0 5733.0 6243.1 6249.8 6278.9 6319.7 6345.2 5782.2 5795.1 5808.5 5816.7 5832.6 5395.4 5403.3 5416.2 5442.5 5451.7 6223.1 6289.9 6302.7 6337.4 6427.4 6252.8 6306.2 6310.3 6330.8 6336.9 6230.2 6371.7 6375.3 6375.8 6383.6 6144.6 6153.6 6218.5 6273.6 6279.8 6312.9 6313.2 6321.9 6324.6 6329.3 6018.9 6107.1 6191.7 6193.1 6211.0 5955.1 6081.3 6104.9 6106.6 6141.0 5962.8 6017.3 6028.3 6057.8 6064.5 6179.2 6199.7 6199.8 6200.5 6212.4 5860.1 5909.3 5966.1 5998.0 6016.1 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:67028.76 Median:21540.18 stddev:141639.78 (657.6%) stddev-sans-first:141949.03 05:19:52 INFO - PROCESS | 1669 | Values: 497.5 497.6 501.3 534.0 536.3 31279.5 41470.4 42555.6 42643.7 42647.0 43939.8 44363.0 44460.6 44483.6 44564.0 8654.2 9497.2 9702.7 9707.3 9720.8 8666.4 11507.5 11792.4 11835.2 12075.9 11808.8 11898.6 11909.8 11914.8 11916.8 8659.4 8869.3 8894.7 11531.0 11566.5 8756.1 11324.5 11733.3 11734.7 12002.5 41301.6 41649.8 41662.8 41709.8 42037.6 3453.5 3495.0 3925.5 4209.2 4233.2 31118.4 32031.2 32402.8 32427.4 32559.2 2718.7 2893.8 3289.5 3330.8 3361.7 390309.0 393046.0 393111.0 393480.0 393522.0 60740.0 60921.7 61103.0 61230.7 61247.4 64224.7 64238.7 64243.7 64386.0 64576.7 16424.2 16691.4 16704.7 16719.7 16726.4 11734.3 11880.2 12818.3 12904.7 12967.0 16074.7 16079.0 16115.8 16131.9 16254.5 5725.9 6068.9 6360.1 6399.0 6414.3 769.8 866.3 912.4 1027.1 1084.2 29441.7 29733.3 29766.3 30485.5 30753.5 921.1 1008.7 1080.8 1104.6 1105.3 13515.5 14837.3 15456.8 15601.4 16007.7 631736.0 636875.0 637481.0 638662.8 639154.0 59088.4 59195.0 59293.7 59332.7 59502.0 1288.8 1407.4 1434.6 1444.1 1450.6 28610.0 30852.8 31040.0 31063.8 31806.2 50198.0 50505.0 50743.0 50869.0 51119.5 533685.0 534317.0 534872.0 536081.0 536223.0 34124.8 40696.0 41930.8 42027.8 43492.8 48909.0 49009.0 49803.8 49927.5 50302.0 38426.4 39563.2 39868.2 40232.8 40575.0 33112.5 38157.8 38531.4 39154.8 39870.0 34081.0 37564.2 38713.2 38877.0 38882.6 28595.1 32686.7 33385.2 33663.5 34527.7 24911.9 25181.6 25371.0 25848.0 26321.2 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:29562.03 Median:135182.71 stddev:77126.62 (57.1%) stddev-sans-first:77311.86 05:19:52 INFO - PROCESS | 1669 | Values: 383.6 385.7 388.8 405.2 411.3 12888.9 20046.7 21459.0 21484.2 21516.1 20160.4 20417.1 20955.0 21097.4 21146.4 4934.5 5267.8 6042.0 6052.1 6066.7 5807.5 5950.2 6014.6 6064.5 6066.5 5640.9 5930.5 5942.5 5950.6 5954.8 4937.8 5923.5 5935.9 5937.3 5990.4 5098.8 5255.8 5281.6 5287.9 5288.9 23592.4 24995.1 25365.5 25900.5 26030.7 1935.5 2189.2 2291.6 2349.1 2880.8 9141.9 10237.3 10568.7 13500.0 14223.5 1597.8 1847.7 1897.0 1965.8 1966.7 104700.5 108626.9 108636.0 114548.0 115839.0 11405.6 11488.0 11644.4 11655.8 11766.5 14767.4 14835.6 14880.4 14890.6 14923.1 8401.7 8582.4 8647.2 8689.7 8695.2 8487.4 8487.6 8489.3 8621.7 8636.4 4502.7 4573.3 4586.7 4615.9 4637.7 1640.2 1650.6 1794.7 1887.0 1956.6 771.0 833.8 979.4 994.7 1004.8 20549.4 21219.8 21261.0 21263.7 21328.0 795.4 821.0 976.1 1009.6 1049.9 9034.9 10712.5 10754.0 11015.7 11240.7 246773.0 247409.0 247513.0 639378.0 644851.0 97506.5 107646.5 144564.0 149890.0 151543.0 1010.3 1011.5 1022.2 1071.8 1077.4 18696.1 19030.9 19125.4 19130.4 19203.6 55394.0 55461.6 56945.4 57746.0 57746.1 63834.0 74221.0 76913.0 79080.8 88709.0 8180.7 8229.8 8294.8 8356.4 8364.2 12740.7 12952.9 13009.8 13219.0 13291.1 5585.5 9029.6 9098.8 11124.5 11481.5 11034.9 11167.8 11298.4 11335.6 11484.4 11062.9 11481.9 11509.7 11717.3 11726.4 7079.1 7395.1 7420.0 8363.0 8388.6 8123.9 10132.2 11217.8 11329.3 11379.1 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5781.51 Median:4904.06 stddev:3284.52 (67.0%) stddev-sans-first:3276.97 05:19:52 INFO - PROCESS | 1669 | Values: 1366.2 1543.6 1568.6 1822.7 1998.9 7897.2 8687.0 8913.3 8970.3 9031.9 8633.1 8876.9 8881.8 8888.9 8891.6 1742.9 2172.2 2175.4 2370.9 2413.3 1959.0 2038.7 2197.4 2225.2 2437.5 2399.7 2405.2 2405.3 2406.4 2406.5 2335.5 2377.8 2384.8 2386.3 2386.5 2131.9 2168.9 2183.9 2398.1 2414.6 8797.9 8875.3 8877.5 8884.0 8886.0 5229.1 5402.3 5409.4 5412.4 5412.9 3375.3 3484.4 3558.2 3563.1 3568.9 935.1 954.5 1020.8 1020.8 1050.6 11858.6 11960.1 11960.9 11971.5 11974.6 4712.5 4781.0 4849.2 4861.6 4871.9 10062.8 10120.1 10121.5 10136.8 10138.9 5316.1 5393.0 5401.0 5402.6 5408.1 4428.4 4776.6 4873.7 4934.4 4943.1 3728.6 3937.5 3975.1 4043.5 4054.8 1273.6 1285.0 1285.7 1297.3 1297.9 886.9 907.2 946.6 967.8 989.6 5994.8 6033.3 6149.2 6268.4 6313.2 914.9 946.4 949.3 955.2 970.1 4038.4 4120.1 4126.4 4178.6 4191.3 10696.2 10986.2 11072.9 11079.4 11099.9 10948.0 11062.4 11071.4 11073.1 11074.3 7921.1 8286.4 8297.9 8404.2 8443.5 5412.8 6001.2 6128.0 6246.5 6253.3 10033.9 10326.4 10331.3 10336.6 10339.9 9808.2 10308.6 10342.2 10410.5 10426.4 6004.3 7069.4 7295.5 7745.2 7771.4 8263.8 8510.9 8511.0 8626.9 8632.7 6291.4 7541.9 7555.1 7680.8 7692.0 7410.6 7441.8 7450.5 7479.9 7509.8 6170.8 7107.4 7113.9 7169.1 7174.2 4969.2 5006.5 5027.7 5102.9 5175.3 4215.9 5074.5 5183.6 5233.8 5338.4 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4470.84 Median:5053.58 stddev:1381.36 (27.3%) stddev-sans-first:1348.62 05:19:52 INFO - PROCESS | 1669 | Values: 261.6 297.4 311.9 315.9 316.3 5200.3 5331.1 5347.0 5359.3 5366.0 3744.2 3867.9 4309.1 5092.3 5291.3 3998.3 4002.8 4053.0 4068.9 4071.7 3314.8 3442.0 3587.8 4037.4 4118.4 3787.8 3894.8 3928.5 4008.4 4061.6 3526.9 4029.3 4041.5 4059.9 4065.2 2896.3 3082.1 3302.6 3485.3 3521.9 4590.5 4960.7 4972.0 5132.6 5144.8 4263.2 4628.5 4640.9 4641.2 4675.9 4961.8 5532.4 5568.3 5572.1 5619.3 3655.6 3876.5 3908.1 3922.9 3936.3 5363.6 5841.4 5841.6 5933.7 5958.6 3657.5 3692.4 3707.2 3790.3 3960.9 3460.3 3922.0 3937.5 4010.1 4023.6 3907.8 4162.6 4431.0 4598.3 4624.8 5564.8 5604.3 5613.7 5645.0 5647.9 1289.9 1326.1 1326.4 1345.1 1379.6 672.0 801.0 827.6 836.5 889.0 4528.1 4781.7 5065.3 5235.6 5262.4 5305.6 5525.7 5660.0 5710.2 5735.8 4781.9 4910.7 5087.9 5167.6 5189.7 2594.5 2993.5 3010.5 3015.7 3031.7 4974.9 5432.3 5656.9 5863.9 5948.5 5041.8 5425.9 5861.3 5866.3 5873.4 5159.9 5190.6 5450.0 5970.0 5987.9 5269.4 5321.5 5702.3 5721.4 5763.7 4768.3 4803.7 4881.3 5334.0 5345.9 4953.5 5118.5 5935.6 5936.6 5987.3 3249.5 3886.7 3919.0 3940.0 3941.1 5258.7 5640.3 5654.9 5683.7 5751.8 4827.2 5298.3 5469.9 5474.6 5495.8 5228.7 5481.5 5501.8 5511.6 5611.4 5262.3 5472.5 5478.8 5514.8 5552.0 4826.5 4995.6 5515.4 5560.8 5583.3 4479.2 5433.4 5476.2 5507.9 5528.7 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:17101.17 Median:57342.00 stddev:22995.47 (40.1%) stddev-sans-first:23026.80 05:19:52 INFO - PROCESS | 1669 | Values: 648.7 681.5 726.9 766.6 840.3 14301.9 14502.6 14538.7 14573.2 14855.3 13967.7 14036.4 14108.6 14118.0 14132.0 682.7 713.3 872.5 890.2 908.5 880.1 885.3 889.8 889.8 894.0 942.1 943.5 943.9 945.7 947.3 722.1 765.9 901.8 939.4 942.3 698.5 698.6 699.1 700.6 702.2 14355.2 14391.9 14400.0 14401.5 14405.9 12930.6 13169.4 13298.9 13365.8 13392.5 10035.6 11729.5 11994.2 12000.4 12567.1 4393.1 4403.1 4435.3 4594.6 4604.4 76915.0 77053.0 77495.0 77915.6 77965.6 10479.6 10612.3 10628.7 10651.1 10745.9 18983.6 19000.9 19023.8 19044.6 19075.1 7349.4 7355.4 7482.3 7627.9 7966.0 4418.6 4422.8 4470.9 4494.8 4620.0 3184.8 3297.4 3299.3 3299.6 3304.6 578.4 626.8 631.7 647.7 648.1 469.4 601.4 641.5 645.7 650.1 8666.3 9111.3 9155.2 9186.5 9358.5 627.9 663.5 686.5 693.5 703.7 4241.3 4337.5 4354.6 4362.4 4501.7 65151.3 84780.6 86220.0 86350.1 86362.3 61214.4 69795.7 71773.9 72450.0 75026.6 64730.4 64996.7 65760.1 65875.0 65877.0 7151.6 8051.5 8371.4 8654.6 8757.7 31757.0 42818.6 42907.6 44064.3 44412.3 55743.0 55996.0 57328.0 57356.0 57447.7 9846.7 10107.2 10130.2 10300.4 10373.3 12226.8 12385.8 12515.7 12758.2 12835.3 11490.4 11842.5 11848.2 11882.4 11963.0 11562.9 11663.6 11886.1 11899.9 12046.3 11152.6 11278.4 11484.5 11667.0 11748.7 8059.5 9789.3 10073.6 10264.7 10362.7 9108.2 9516.3 9564.6 9802.0 9950.6 05:19:52 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:19:52 INFO - PROCESS | 1669 | 05:19:52 INFO - TEST-INFO | 1669: exit 0 05:19:52 INFO - TEST-OK | dromaeo_css | took 1116190ms 05:19:52 INFO - TEST-START | kraken 05:19:52 INFO - operating with platform_type : mac_ 05:19:52 INFO - Initialising browser for kraken test... 05:19:52 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpfw7u8U/profile http://localhost:49274/getInfo.html) 05:19:54 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:19:54 INFO - PROCESS | 1674 | colorDepth:24 05:19:54 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:19:54 INFO - PROCESS | 1674 | __metrics 05:19:55 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:19:55 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:19:55 INFO - TEST-INFO | 1674: exit 0 05:19:55 INFO - Browser initialized. 05:19:55 INFO - Running cycle 1/1 for kraken test... 05:19:55 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpfw7u8U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:07 INFO - PROCESS | 1675 | RSS: Main: 241573888 05:20:07 INFO - PROCESS | 1675 | 05:20:10 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 05:20:11 INFO - PROCESS | 1675 | RSS: Main: 292216832 05:20:11 INFO - PROCESS | 1675 | 05:20:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 05:20:14 INFO - PROCESS | 1675 | RSS: Main: 301838336 05:20:14 INFO - PROCESS | 1675 | 05:20:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 05:20:18 INFO - PROCESS | 1675 | RSS: Main: 385437696 05:20:18 INFO - PROCESS | 1675 | 05:20:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 05:20:21 INFO - PROCESS | 1675 | RSS: Main: 288206848 05:20:21 INFO - PROCESS | 1675 | 05:20:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:20:23 INFO - PROCESS | 1675 | RSS: Main: 292200448 05:20:23 INFO - PROCESS | 1675 | 05:20:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 05:20:27 INFO - PROCESS | 1675 | RSS: Main: 328527872 05:20:27 INFO - PROCESS | 1675 | 05:20:30 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 05:20:30 INFO - PROCESS | 1675 | RSS: Main: 329146368 05:20:30 INFO - PROCESS | 1675 | 05:20:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 05:20:34 INFO - PROCESS | 1675 | RSS: Main: 327925760 05:20:34 INFO - PROCESS | 1675 | 05:20:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:36 INFO - PROCESS | 1675 | RSS: Main: 309825536 05:20:36 INFO - PROCESS | 1675 | 05:20:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:39 INFO - PROCESS | 1675 | RSS: Main: 316993536 05:20:39 INFO - PROCESS | 1675 | 05:20:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:20:41 INFO - PROCESS | 1675 | RSS: Main: 321105920 05:20:41 INFO - PROCESS | 1675 | 05:20:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:45 INFO - PROCESS | 1675 | RSS: Main: 322809856 05:20:45 INFO - PROCESS | 1675 | 05:20:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:48 INFO - PROCESS | 1675 | RSS: Main: 324136960 05:20:48 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 05:20:50 INFO - PROCESS | 1675 | RSS: Main: 367562752 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | __start_tp_report 05:20:50 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 05:20:50 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 05:20:50 INFO - PROCESS | 1675 | |i|pagename|runs| 05:20:50 INFO - PROCESS | 1675 | |0;ai-astar;113;105;112;114;113;112;112;116;163;155 05:20:50 INFO - PROCESS | 1675 | |1;audio-beat-detection;173;140;141;142;119;140;139;116;166;102 05:20:50 INFO - PROCESS | 1675 | |2;audio-dft;183;183;182;183;184;182;183;184;184;179 05:20:50 INFO - PROCESS | 1675 | |3;audio-fft;78;74;57;105;58;56;58;64;95;55 05:20:50 INFO - PROCESS | 1675 | |4;audio-oscillator;89;92;92;72;79;69;97;73;68;103 05:20:50 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;87;91;87;91;87;90;89;90;87 05:20:50 INFO - PROCESS | 1675 | |6;imaging-darkroom;95;96;95;94;94;118;149;95;95;95 05:20:50 INFO - PROCESS | 1675 | |7;imaging-desaturate;84;87;83;85;83;86;84;86;87;86 05:20:50 INFO - PROCESS | 1675 | |8;json-parse-financial;58;56;54;55;55;55;55;57;54;55 05:20:50 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;65;77;81;73;71;70;70;72;70;71 05:20:50 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;83;86;84;93;86;85;85;86;121;85 05:20:50 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;141;140;138;140;141;126;141;142;158;133 05:20:50 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;166;164;163;164;166;164;161;164;162;160 05:20:50 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;65;57;66;85;85;85;71;66;65;65 05:20:50 INFO - PROCESS | 1675 | __end_tp_report 05:20:50 INFO - PROCESS | 1675 | __start_cc_report 05:20:50 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1978 05:20:50 INFO - PROCESS | 1675 | __end_cc_report 05:20:50 INFO - PROCESS | 1675 | __startTimestamp1464697250782__endTimestamp 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | ------- Summary: start ------- 05:20:50 INFO - PROCESS | 1675 | Number of tests: 14 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:121.50 Median:113.50 stddev:20.05 (17.7%) stddev-sans-first:21.03 05:20:50 INFO - PROCESS | 1675 | Values: 113.0 105.0 112.0 114.0 113.0 112.0 112.0 116.0 163.0 155.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:140.50 stddev:21.56 (15.3%) stddev-sans-first:18.73 05:20:50 INFO - PROCESS | 1675 | Values: 173.0 140.0 141.0 142.0 119.0 140.0 139.0 116.0 166.0 102.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 05:20:50 INFO - PROCESS | 1675 | Values: 183.0 183.0 182.0 183.0 184.0 182.0 183.0 184.0 184.0 179.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:70.00 Median:61.00 stddev:17.78 (29.1%) stddev-sans-first:18.62 05:20:50 INFO - PROCESS | 1675 | Values: 78.0 74.0 57.0 105.0 58.0 56.0 58.0 64.0 95.0 55.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:84.00 stddev:12.69 (15.1%) stddev-sans-first:13.30 05:20:50 INFO - PROCESS | 1675 | Values: 89.0 92.0 92.0 72.0 79.0 69.0 97.0 73.0 68.0 103.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:89.50 stddev:1.70 (1.9%) stddev-sans-first:1.79 05:20:50 INFO - PROCESS | 1675 | Values: 88.0 87.0 91.0 87.0 91.0 87.0 90.0 89.0 90.0 87.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:95.00 stddev:17.86 (18.8%) stddev-sans-first:18.73 05:20:50 INFO - PROCESS | 1675 | Values: 95.0 96.0 95.0 94.0 94.0 118.0 149.0 95.0 95.0 95.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.52 (1.8%) stddev-sans-first:1.56 05:20:50 INFO - PROCESS | 1675 | Values: 84.0 87.0 83.0 85.0 83.0 86.0 84.0 86.0 87.0 86.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:0.93 05:20:50 INFO - PROCESS | 1675 | Values: 58.0 56.0 54.0 55.0 55.0 55.0 55.0 57.0 54.0 55.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.50 stddev:4.35 (6.1%) stddev-sans-first:3.80 05:20:50 INFO - PROCESS | 1675 | Values: 65.0 77.0 81.0 73.0 71.0 70.0 70.0 72.0 70.0 71.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:85.50 stddev:11.42 (13.4%) stddev-sans-first:11.88 05:20:50 INFO - PROCESS | 1675 | Values: 83.0 86.0 84.0 93.0 86.0 85.0 85.0 86.0 121.0 85.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:141.00 stddev:8.03 (5.7%) stddev-sans-first:8.51 05:20:50 INFO - PROCESS | 1675 | Values: 141.0 140.0 138.0 140.0 141.0 126.0 141.0 142.0 158.0 133.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:164.00 stddev:1.96 (1.2%) stddev-sans-first:1.83 05:20:50 INFO - PROCESS | 1675 | Values: 166.0 164.0 163.0 164.0 166.0 164.0 161.0 164.0 162.0 160.0 05:20:50 INFO - PROCESS | 1675 | 05:20:50 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:68.50 stddev:10.23 (14.9%) stddev-sans-first:10.62 05:20:50 INFO - PROCESS | 1675 | Values: 65.0 57.0 66.0 85.0 85.0 85.0 71.0 66.0 65.0 65.0 05:20:50 INFO - PROCESS | 1675 | -------- Summary: end -------- 05:20:50 INFO - PROCESS | 1675 | 05:20:51 INFO - TEST-INFO | 1675: exit 0 05:20:51 INFO - TEST-OK | kraken | took 58793ms 05:20:51 INFO - SUITE-END | took 1174s 05:20:51 INFO - Completed test suite (00:19:35) 05:20:51 INFO - javascript benchmark 05:20:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3468.3063387322536, 3487.511748355231, 3499.7200223982086, 3512.3502152101846, 3557.1107223194203, 5544.0575102232615, 5578.107502799553, 5673.943260567393, 5751.051076572366, 5769.740361683724, 5675.659460432377, 5751.338596061454, 5814.244148260731, 5821.854453638661, 5839.7664093436215, 5133.614978876581, 5142.768575414097, 5209.973950130244, 5218.060749065166, 5304.655197412156, 5249.737513124348, 5316.787328506855, 5320.574354051686, 5321.707306098163, 5351.304330437038, 5110.923336149942, 5148.3564554430695, 5169.1212493875755, 5172.146595811687, 5210.869728256805, 5187.688738675653, 5194.039102766001, 5198.896022079537, 5199.376074871002, 5221.0, 5217.060929032773, 5227.2159176123505, 5232.764525596319, 5233.031889100528, 5269.789208431658, 5590.664560126405, 5641.294838145232, 5739.311282646067, 5758.539316854642, 5814.883702325972, 6235.812925612239, 6292.2134733158355, 6310.779122730728, 6323.051542268697, 6336.96831515844, 5248.448912864133, 6244.750209991595, 6345.460635845959, 6345.587536810092, 6355.555111142222, 5710.543156547466, 5756.42435756421, 5773.797917072966, 5879.382664820175, 5886.264216972879, 6250.374962503804, 6304.401081897213, 6307.211598550181, 6309.873802523924, 6318.052292156121, 6181.690915454209, 6196.3803619638575, 6199.318075011745, 6209.720562574695, 6210.068489726577, 6140.534923917717, 6185.0, 6195.597286176474, 6232.968835155795, 6277.811665650038, 6146.354632763585, 6153.446189842935, 6181.474574661114, 6198.535109866778, 6311.526635502264, 6315.273743519462, 6322.304546499881, 6332.1135041094285, 6333.809985700436, 6363.204599425072, 4903.0, 4955.900881982412, 4956.553910148032, 4957.380327459067, 4990.700557966534, 4324.675649326313, 4407.140607581555, 4438.090191510732, 4445.022095139064, 4467.486239082547, 5715.0, 5722.971385143131, 5723.313202415654, 5727.026405511073, 5732.971335143297, 6243.0635404688755, 6249.75000999964, 6278.905816412757, 6319.715612797435, 6345.206849143857, 5782.2194003808945, 5795.072788353843, 5808.477237048686, 5816.651000939957, 5832.562557808182, 5395.352557693023, 5403.324584426947, 5416.160495123183, 5442.537384322298, 5451.700156491352, 6223.097650840603, 6289.8742025159245, 6302.684865756693, 6337.429631333202, 6427.421532062137, 6252.843678908063, 6306.211723534559, 6310.274318453253, 6330.778422755181, 6336.904946425715, 6230.15892854477, 6371.681415929278, 6375.266844312963, 6375.776847810304, 6383.553151279366, 6144.603604594123, 6153.600015998945, 6218.533609979179, 6273.592216505913, 6279.843003924939, 6312.905306420315, 6313.179286692701, 6321.936780632135, 6324.620522768465, 6329.335419780857, 6018.9398106020135, 6107.144999699956, 6191.690415479298, 6193.102000210034, 6211.0372892201785, 5955.136505206808, 6081.300650425081, 6104.938950610437, 6106.603070800384, 6141.0, 5962.821115366546, 6017.338092809699, 6028.33688294296, 6057.848553786014, 6064.514838812973, 6179.16581261524, 6199.721012554356, 6199.783007594712, 6200.503959683124, 6212.378762123752, 5860.120981852756, 5909.349971503045, 5966.105084237399, 5998.0, 6016.067509536029], "value": 5739.515306568966, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.5273490184326, 497.6342388344568, 501.2556353814585, 534.0360649028504, 536.3215532351576, 31279.530807037914, 41470.37794433082, 42555.57444425555, 42643.72068837932, 42647.0, 43939.78030109845, 44363.0, 44460.55539444605, 44483.55516444835, 44564.0, 8654.177853103945, 9497.192738617208, 9702.66040688576, 9707.320487565874, 9720.75698107546, 8666.350023748213, 11507.482163302671, 11792.410379481034, 11835.230710003822, 12075.93962030193, 11808.822867656992, 11898.643040708792, 11909.821352679717, 11914.761704765899, 11916.821247681291, 8659.437136586102, 8869.290456763443, 8894.688685896028, 11531.019863311629, 11566.47950842214, 8756.124387561256, 11324.49039793203, 11733.296002239893, 11734.711731212417, 12002.51989920402, 41301.586984130074, 41649.75050748482, 41662.79168604168, 41709.79145104255, 42037.579624203674, 3453.4819777033395, 3494.965050349515, 3925.548561915359, 4209.158168366308, 4233.153369326116, 31118.37763244745, 32031.199220019455, 32402.837985810154, 32427.351452971045, 32559.18602034968, 2718.7009428962797, 2893.7829662775375, 3289.4572395554706, 3330.7668463207583, 3361.7310615150973, 390309.0, 393046.0, 393111.0, 393480.0, 393522.03238983906, 60740.0, 60921.69539152276, 61103.0, 61230.693846530485, 61247.38752612417, 64224.67887660532, 64238.715225695225, 64243.67878160673, 64386.0, 64576.67711661506, 16424.1787910604, 16691.41580044717, 16704.74942875858, 16719.749203761952, 16726.414575489798, 11734.295942243492, 11880.227785193934, 12818.294993775246, 12904.677383065498, 12967.0, 16074.678506429806, 16079.035257884565, 16115.758263626056, 16131.919340403458, 16254.51236462908, 5725.914111288334, 6068.908966365508, 6360.0777887206095, 6399.0, 6414.294427613052, 769.8267889724767, 866.3026263857529, 912.370464379576, 1027.1012863744224, 1084.1976937066672, 29441.705582944323, 29733.256668583457, 29766.25584360365, 30485.542716859265, 30753.538696919564, 921.1065266691298, 1008.7326858382535, 1080.8486811846349, 1104.5526561742506, 1105.2539535813294, 13515.526956556474, 14837.332320045409, 15456.768148477782, 15601.375944962301, 16007.679846403007, 631736.0, 636875.0, 637481.0, 638662.8066859728, 639154.0, 59088.40911590829, 59195.0, 59293.703531482926, 59332.70333648304, 59502.0, 1288.8260084888607, 1407.3737186951707, 1434.63416828708, 1444.097439100562, 1450.6445920749484, 28609.99865004715, 30852.845735772076, 31040.0, 31063.844680776452, 31806.204844879063, 50198.0, 50505.0, 50743.0, 50869.0, 51119.488805111476, 533685.0, 534317.0, 534872.0, 536081.0, 536223.0, 34124.82937585396, 40695.98260043404, 41930.790346048074, 42027.7898610505, 43492.782536087114, 48909.0, 49009.0, 49803.75098124486, 49927.500724992286, 50302.0, 38426.42360364653, 39563.20873582455, 39868.2026359477, 40232.79301620956, 40575.0, 33112.50331244985, 38157.80921095377, 38531.42202866903, 39154.80422597869, 39870.0, 34081.0, 37564.248715025, 38713.22573548457, 38877.0, 38882.61117388903, 28595.142145735663, 32686.673133268363, 33385.165370864954, 33663.495047573815, 34527.65472345244, 24911.878965446234, 25181.622275666246, 25370.98516059337, 25847.966081356535, 26321.21036368912], "value": 20311.499791732058, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.5608228578278, 385.6779589043149, 388.79005337117957, 405.2280405826897, 411.2679430613509, 12888.935555322221, 20046.69929951048, 21459.0, 21484.248051318205, 21516.139354425803, 20160.395188144343, 20417.08123134459, 20955.0, 21097.367078987616, 21146.365609031753, 4934.5312195341385, 5267.789288428458, 6041.9697901510435, 6052.122442245872, 6066.6663333516635, 5807.506361959238, 5950.196723442323, 6014.639121652693, 6064.514838812885, 6066.514678825705, 5640.915386269209, 5930.466258036776, 5942.524598032169, 5950.613210141349, 5954.791582294621, 4937.80248790048, 5923.466887980081, 5935.88128237435, 5937.257842769675, 5990.400959904018, 5098.821541246076, 5255.763490642892, 5281.577473802086, 5287.894242115175, 5288.947110528922, 23592.410189745224, 24995.12517061894, 25365.492690146093, 25900.481990360277, 26030.73969260302, 1935.4870959195684, 2189.2337681811396, 2291.6218823894037, 2349.0721165139676, 2880.8127471714483, 9141.908580914172, 10237.283390162691, 10568.678915135608, 13500.0, 14223.502177423845, 1597.752348386002, 1847.7228415737748, 1896.9620607587772, 1965.7837637859825, 1966.6754985427388, 104700.47649761778, 108626.91373086246, 108636.0, 114548.0, 115838.99999999916, 11405.600803971822, 11488.0, 11644.41777911101, 11655.825162622568, 11766.52933882635, 14767.409303627735, 14835.629109272355, 14880.404783808526, 14890.627734306512, 14923.104613722995, 8401.747947561584, 8582.356323275779, 8647.221750042527, 8689.6524139035, 8695.217430431165, 8487.448315859448, 8487.618057187441, 8489.278411335106, 8621.655133794704, 8636.438631489058, 4502.662300327489, 4573.314002899525, 4586.724796512153, 4615.861524154281, 4637.652176086807, 1640.2045008171028, 1650.5626009107596, 1794.6590147871862, 1886.990565047166, 1956.6380219659409, 770.9884351734728, 833.8165603567205, 979.3536266064364, 994.7165057958447, 1004.7990401919645, 20549.38351849447, 21219.78780212178, 21261.0, 21263.68104478434, 21328.0, 795.3756301303449, 820.9876851847227, 976.0727309056425, 1009.6112996496255, 1049.9422531760674, 9034.86447703285, 10712.517936692868, 10754.0, 11015.724606884733, 11240.662780116447, 246773.0, 247409.0, 247513.0, 639378.0, 644851.0, 97506.53740193903, 107646.46176769222, 144564.0, 149890.0, 151543.0, 1010.2978429991233, 1011.4891979550428, 1022.1975749037119, 1071.8285074388216, 1077.3966578715945, 18696.065196739837, 19030.904845476238, 19125.426237212905, 19130.426087217405, 19203.580938297553, 55394.0, 55461.63036577572, 56945.43054569401, 57746.0, 57746.133807993756, 63834.04248936177, 74221.0, 76913.0, 79080.81378779208, 88709.0, 8180.672773089248, 8229.79425514367, 8294.792630184053, 8356.415050946376, 8364.16358364183, 12740.74518509606, 12952.935235323763, 13009.804852927024, 13219.0, 13291.136076155019, 5585.469380408855, 9029.638814447611, 9098.818023639622, 11124.499397527292, 11481.540738370371, 11034.889651103385, 11167.832482512606, 11298.378589177673, 11335.603253886075, 11484.425778711198, 11062.893710628872, 11481.885181148082, 11509.712257193638, 11717.296962182296, 11726.413679315829, 7079.115110611174, 7395.112586489656, 7419.962900185465, 8363.0, 8388.622511987098, 8123.94388729485, 10132.189424846141, 11217.831732523855, 11329.263597866113, 11379.146564007568], "value": 9438.407405331218, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.2349084512668, 1543.5678010157153, 1568.6392129810142, 1822.6719190545705, 1998.9400317990455, 7897.2497612726775, 8687.044425113232, 8913.286937045037, 8970.2823774098, 9031.909680903189, 8633.136686331363, 8876.911230887688, 8881.777955551115, 8888.866667, 8891.644334226623, 1742.860571154311, 2172.196287373674, 2175.4235127691095, 2370.8814559272055, 2413.263954493879, 1958.9804101959012, 2038.7247721557549, 2197.4067001909475, 2225.2100504320974, 2437.4881274932195, 2399.7240317363553, 2405.2062819269686, 2405.338531903723, 2406.434487895352, 2406.5427568761966, 2335.4978679583887, 2377.7741114594087, 2384.7972922301624, 2386.2721869829693, 2386.5107652931106, 2131.8827464489445, 2168.8915554222385, 2183.875304218329, 2398.0647547456506, 2414.6498757680215, 8797.912020879838, 8875.28997680184, 8877.467351958838, 8883.955580222058, 8886.0, 5229.137192363255, 5402.297701298845, 5409.404965453797, 5412.377576578665, 5412.91880621791, 3375.3418083473734, 3484.4250698634696, 3558.19940513385, 3563.1448452371246, 3568.946465803015, 935.1396715022128, 954.5227386306847, 1020.7703266764993, 1020.775429405537, 1050.6217761605815, 11858.644240672707, 11960.102992275613, 11960.940195299056, 11971.461284242143, 11974.58088966882, 4712.512330667, 4780.95219047805, 4849.224124140192, 4861.6353773467135, 4871.878203044952, 10062.849057264148, 10120.089191972758, 10121.544530496145, 10136.847947280648, 10138.898611013943, 5316.069687804618, 5392.975334686397, 5401.027814993339, 5402.567794576425, 5408.080626293539, 4428.446444194476, 4776.617870570346, 4873.731944743002, 4934.383202099738, 4943.085529177114, 3728.5898551159353, 3937.547182074098, 3975.1453437511072, 4043.534993475788, 4054.7769872656695, 1273.611548477723, 1285.0, 1285.7299967007034, 1297.2605614799475, 1297.863724308953, 886.8581027035643, 907.1518130547959, 946.6402766948515, 967.7612655800586, 989.5794287427815, 5994.790182343597, 6033.276006879202, 6149.169862068653, 6268.404501572343, 6313.179286692701, 914.9359544831931, 946.3564775952418, 949.3307218411004, 955.1546881010356, 970.0784254957678, 4038.414429907647, 4120.052387950728, 4126.42230087788, 4178.561251068655, 4191.287481128215, 10696.197785166143, 10986.230963832455, 11072.944635276772, 11079.390633515073, 11099.944500277446, 10947.959943805326, 11062.39156846365, 11071.446427678584, 11073.114150867912, 11074.335539867634, 7921.128675845653, 8286.41995060352, 8297.875531867026, 8404.20160084791, 8443.493390396596, 5412.771036403296, 6001.189839371629, 6127.969360153171, 6246.5002799775, 6253.3121356651645, 10033.949830250802, 10326.432046237229, 10331.32846364984, 10336.586536538454, 10339.948300258451, 9808.16630586408, 10308.587656493657, 10342.224333174892, 10410.47947602632, 10426.374417534973, 6004.309504406874, 7069.363757261871, 7295.489315748059, 7745.186755390603, 7771.37828973692, 8263.752087437386, 8510.872336915076, 8510.957445212734, 8626.870596941177, 8632.654693812177, 6291.370862913672, 7541.924580754122, 7555.055618047744, 7680.846383072419, 7691.961585185928, 7410.555366678017, 7441.776746697607, 7450.515716478411, 7479.925200747923, 7509.774706758806, 6170.79669464468, 7107.360337569644, 7113.8577228454105, 7169.103862017248, 7174.174969878322, 4969.229769385751, 5006.524380183877, 5027.723475208899, 5102.948970510248, 5175.301334319819, 4215.915681686411, 5074.467180945948, 5183.637145399785, 5233.764480598306, 5338.412774594869], "value": 4619.0875403687305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.56971781419566, 297.3948015787872, 311.9266972261518, 315.9289159939013, 316.3246468789134, 5200.349956255468, 5331.093714068608, 5346.984073026122, 5359.338605032438, 5366.0, 3744.1949980754134, 3867.9226415471744, 4309.0735491869245, 5092.28707980883, 5291.28567643368, 3998.340273854825, 4002.8398864045403, 4052.9594704053025, 4068.9389659155063, 4071.7149799514045, 3314.8342582870882, 3441.9655803441897, 3587.8385472653795, 4037.3540233562635, 4118.423420721101, 3787.8068408451386, 3894.824732887013, 3928.489296391465, 4008.378701301303, 4061.57353477885, 3526.876559320437, 4029.254587901233, 4041.5150181978056, 4059.939100913489, 4065.1869626074904, 2896.333843216072, 3082.106189205128, 3302.6036875574846, 3485.268093700326, 3521.9471707924404, 4590.517995610447, 4960.6527543072, 4971.9863455300665, 5132.589392848563, 5144.845654630367, 4263.168682106991, 4628.490866004737, 4640.930386044212, 4641.187792136363, 4675.929861052087, 4961.826336078256, 5532.419095994943, 5568.303962004749, 5572.08060669993, 5619.269494965669, 3655.6161603031564, 3876.5348158220913, 3908.062065104383, 3922.8623699127506, 3936.323300155893, 5363.624546281801, 5841.415858414167, 5841.591088623841, 5933.673647949361, 5958.553108516879, 3657.5245218122004, 3692.409214525663, 3707.1844194277214, 3790.2798468290935, 3960.940585891214, 3460.2733425980323, 3921.9998900280248, 3937.488126543531, 4010.135287091458, 4023.637872591481, 3907.765534067965, 4162.5629308922125, 4430.955690443118, 4598.333241679938, 4624.768761561976, 5564.831385409109, 5604.271444712243, 5613.663180209201, 5644.996026410565, 5647.915281270784, 1289.864918871387, 1326.1446367093172, 1326.383231797219, 1345.1391109689807, 1379.6343968848264, 672.0477014273544, 800.9959950200212, 827.5862068965517, 836.4730219961385, 888.9554773141533, 4528.071745292207, 4781.665283430196, 5065.341505604248, 5235.554977826928, 5262.368515778131, 5305.60207984395, 5525.668459892419, 5659.971700141473, 5710.229119068956, 5735.824156048, 4781.856544303677, 4910.680805747615, 5087.949120508822, 5167.612429067835, 5189.662671926313, 2594.5200137974157, 2993.476141675198, 3010.5183170692585, 3015.743661788729, 3031.6968303169947, 4974.850754477371, 5432.261676263637, 5656.896905103547, 5863.885861686266, 5948.464638182584, 5041.82353617629, 5425.864353391197, 5861.325947516005, 5866.266716660418, 5873.383294754075, 5159.922601160985, 5190.6107041972, 5449.99175152598, 5970.0, 5987.892239935626, 5269.446708095748, 5321.521063104339, 5702.287214098238, 5721.370649228498, 5763.682997435181, 4768.260919557474, 4803.687760295569, 4881.267809828554, 5334.0, 5345.904089661688, 4953.504649535018, 5118.462561430996, 5935.614185078129, 5936.584439089395, 5987.34139244674, 3249.5450636911323, 3886.70849686269, 3919.042411349702, 3939.980300098481, 3941.073847645858, 5258.737063146904, 5640.294963129609, 5654.943450565606, 5683.7442315095095, 5751.769929202952, 4827.151980331621, 5298.284731561344, 5469.945300546943, 5474.644148130359, 5495.780168793203, 5228.660137091077, 5481.534069603968, 5501.807436739695, 5511.559075273888, 5611.438856114356, 5262.263453060405, 5472.5348345391085, 5478.753456094565, 5514.80698175562, 5551.9722401387735, 4826.517348265145, 4995.575376093073, 5515.420880807618, 5560.831275802521, 5583.330000399978, 4479.17135329952, 5433.429489903661, 5476.151196564539, 5507.917381239205, 5528.695921724344], "value": 4025.6904965269887, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.7437462202433, 681.5263391942595, 726.8655298769727, 766.6243540665076, 840.3361344537816, 14301.856981430183, 14502.564923052325, 14538.70922581549, 14573.198474083922, 14855.257237138141, 13967.650808729786, 14036.368363423644, 14108.647283817909, 14118.0, 14132.0, 682.662082269275, 713.3116542536477, 872.4765140915432, 890.1632465482442, 908.5230254116576, 880.0759202837028, 885.3271513649609, 889.750869756466, 889.8264838356523, 893.9553022348889, 942.1332374215738, 943.4575119306392, 943.8678584998106, 945.6831961292941, 947.2658689515612, 722.1334398721528, 765.9425543084235, 901.7970956534792, 939.4034787909726, 942.3026960049548, 698.4796326736614, 698.6332175607846, 699.1295836683311, 700.6391708270245, 702.1854648607641, 14355.210463424508, 14391.928040359731, 14400.0, 14401.495947641886, 14405.92797036008, 12930.612081637471, 13169.407376668072, 13298.86701132986, 13365.799513007312, 13392.531261405902, 10035.648752293708, 11729.472173752116, 11994.220375675553, 12000.399980001052, 12567.057470689735, 4393.07745373472, 4403.141387429452, 4435.3125265583885, 4594.632429405673, 4604.401427814396, 76915.0, 77053.0, 77495.0, 77915.61042194752, 77965.61017194878, 10479.633212837514, 10612.310199836986, 10628.734281643021, 10651.094656954245, 10745.902619930595, 18983.62032759337, 19000.904995474935, 19023.809761902205, 19044.61910761777, 19075.141618627196, 7349.412047036224, 7355.448341374418, 7482.289182527651, 7627.923720762721, 7966.004249468817, 4418.558144185556, 4422.800973956179, 4470.932936005963, 4494.752788596593, 4620.006698559826, 3184.808911465273, 3297.38998285318, 3299.340131973615, 3299.5710557627353, 3304.5869266341706, 578.4360248757512, 626.7743612299569, 631.6778442994106, 647.6567419267795, 648.0764910003227, 469.3757302787285, 601.4436646102425, 641.4900154377282, 645.7191121861911, 650.1353200243665, 8666.306695464347, 9111.271098312252, 9155.22180614642, 9186.540672966325, 9358.531800233415, 627.8712863862897, 663.4824836627438, 686.5468790598181, 693.4591019005184, 703.6763088979025, 4241.321388577874, 4337.522872484024, 4354.564543545684, 4362.447873331563, 4501.729896206238, 65151.348486515475, 84780.57609711912, 86220.0, 86350.13649863546, 86362.27275454455, 61214.38785612176, 69795.65102174456, 71773.92339114918, 72450.0, 75026.62486687531, 64730.35269647243, 64996.675016626505, 65760.06799490057, 65875.0, 65877.0, 7151.578580150051, 8051.4766540174705, 8371.372147089114, 8654.567271636519, 8757.737267881974, 31757.0, 42818.57181428146, 42907.570924290354, 44064.339034915145, 44412.33381499345, 55743.04899328504, 55996.0, 57328.0, 57356.0, 57447.7127614376, 9846.704598862045, 10107.191424686154, 10130.240231982778, 10300.433476158583, 10373.325733827278, 12226.816597750863, 12385.752284954071, 12515.687107822378, 12758.234505929673, 12835.294058826854, 11490.425478725863, 11842.52629894829, 11848.22986505909, 11882.405879706153, 11962.98314643265, 11562.94218528902, 11663.59177428786, 11886.108541859569, 11899.940500297444, 12046.277223366626, 11152.55389784432, 11278.436078196222, 11484.483198255932, 11666.958282300393, 11748.70628234301, 8059.5164290142775, 9789.265805064506, 10073.597056117966, 10264.692059237936, 10362.740931476774, 9108.225800807006, 9516.33385662997, 9564.569594368131, 9802.019798020427, 9950.601975920883], "value": 6886.464188499414, "unit": "score"}], "name": "dromaeo_css", "value": 7213.719739522976, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 112.0, 114.0, 113.0, 112.0, 112.0, 116.0, 163.0, 155.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 140.0, 141.0, 142.0, 119.0, 140.0, 139.0, 116.0, 166.0, 102.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 183.0, 184.0, 182.0, 183.0, 184.0, 184.0, 179.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 57.0, 105.0, 58.0, 56.0, 58.0, 64.0, 95.0, 55.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 92.0, 72.0, 79.0, 69.0, 97.0, 73.0, 68.0, 103.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 91.0, 87.0, 91.0, 87.0, 90.0, 89.0, 90.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 94.0, 118.0, 149.0, 95.0, 95.0, 95.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 83.0, 85.0, 83.0, 86.0, 84.0, 86.0, 87.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 54.0, 55.0, 55.0, 55.0, 55.0, 57.0, 54.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 81.0, 73.0, 71.0, 70.0, 70.0, 72.0, 70.0, 71.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 93.0, 86.0, 85.0, 85.0, 86.0, 121.0, 85.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 138.0, 140.0, 141.0, 126.0, 141.0, 142.0, 158.0, 133.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 163.0, 164.0, 166.0, 164.0, 161.0, 164.0, 162.0, 160.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 57.0, 66.0, 85.0, 85.0, 85.0, 71.0, 66.0, 65.0, 65.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1463.0000000000002, "alertThreshold": 2.0}]} 05:20:51 INFO - Return code: 0 05:20:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:20:52 ERROR - # TBPL SUCCESS # 05:20:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:52 INFO - 2016-05-31 05:20:52.073 screenresolution[1679:10242] starting screenresolution argv=screenresolution get 05:20:52 INFO - 2016-05-31 05:20:52.076 screenresolution[1679:10242] Display 0: 1600x1200x32@60 05:20:52 INFO - 2016-05-31 05:20:52.083 screenresolution[1680:10245] starting screenresolution argv=screenresolution list 05:20:52 INFO - Available Modes on Display 0 05:20:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:52 INFO - Intel Iris: 05:20:52 INFO - Chipset Model: Intel Iris 05:20:52 INFO - Type: GPU 05:20:52 INFO - Bus: Built-In 05:20:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:52 INFO - Vendor: Intel (0x8086) 05:20:52 INFO - Device ID: 0x0a2e 05:20:52 INFO - Revision ID: 0x0009 05:20:52 INFO - Displays: 05:20:52 INFO - Display: 05:20:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:52 INFO - Display Serial Number: 1600x1200 60 05:20:52 INFO - Main Display: Yes 05:20:52 INFO - Mirror: Off 05:20:52 INFO - Online: Yes 05:20:52 INFO - Rotation: Supported 05:20:52 INFO - Return code: 0 05:20:52 INFO - Running post-action listener: _resource_record_post_action 05:20:52 INFO - ##### 05:20:52 INFO - ##### Finished run-tests step (success) 05:20:52 INFO - ##### 05:20:52 INFO - Running post-run listener: _resource_record_post_run 05:20:52 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31403520; Write bytes: 698422272; Read time: 195; Write time: 1890 05:20:52 INFO - TinderboxPrint: CPU usage
53.8% 05:20:52 INFO - TinderboxPrint: I/O read bytes / time
31,403,520 / 195 05:20:52 INFO - TinderboxPrint: I/O write bytes / time
698,422,272 / 1,890 05:20:52 INFO - TinderboxPrint: CPU idle
2,240.8 (46.2%) 05:20:52 INFO - TinderboxPrint: CPU system
967.0 (20.0%) 05:20:52 INFO - TinderboxPrint: CPU user
1,637.8 (33.8%) 05:20:52 INFO - TinderboxPrint: Swap in / out
426,409,984 / 0 05:20:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 392974848; Write bytes: 386983424; Read time: 29450; Write time: 759 05:20:52 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29621760; Write bytes: 295984640; Read time: 181; Write time: 1091 05:20:52 INFO - Running post-run listener: _upload_blobber_files 05:20:52 INFO - Blob upload gear active. 05:20:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:20:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:20:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:54 INFO - (blobuploader) - INFO - Done attempting. 05:20:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:20:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:20:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:55 INFO - (blobuploader) - INFO - Done attempting. 05:20:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:20:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:20:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:57 INFO - (blobuploader) - INFO - Done attempting. 05:20:57 INFO - (blobuploader) - INFO - Iteration through files over. 05:20:57 INFO - Return code: 0 05:20:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:20:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:20:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e7e4d3df2ea2652c05c586efe93f4ba8f67fa5b494d464e75fb41d4e03d8a6e6dbcba3e565aa77568ec5f0e67c57edaac033402e91720e9b1e3077a2d8bfb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9055476330d5cb242b12bbe5ae62018fb17bc216cf9ecce824a80b4d35ffbe00cab20400abeb1ffdbbda75cf027c76f0592d127fcfde78e61838603f46f673", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0c3fd58ac1abd96464ad429aa4a321470700a1be2c8de0d72abd2b1e7b261645f43e12dd3e2137855d3a870c556a9893fa0f87ed9afec1fe6e4bf3dd45a696b"} 05:20:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:20:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:20:57 INFO - Contents: 05:20:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e7e4d3df2ea2652c05c586efe93f4ba8f67fa5b494d464e75fb41d4e03d8a6e6dbcba3e565aa77568ec5f0e67c57edaac033402e91720e9b1e3077a2d8bfb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9055476330d5cb242b12bbe5ae62018fb17bc216cf9ecce824a80b4d35ffbe00cab20400abeb1ffdbbda75cf027c76f0592d127fcfde78e61838603f46f673", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0c3fd58ac1abd96464ad429aa4a321470700a1be2c8de0d72abd2b1e7b261645f43e12dd3e2137855d3a870c556a9893fa0f87ed9afec1fe6e4bf3dd45a696b"} 05:20:57 INFO - Running post-run listener: copy_logs_to_upload_dir 05:20:57 INFO - Copying logs to upload dir... 05:20:57 INFO - mkdir: /builds/slave/test/build/upload/logs 05:20:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1306.606383 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 46 secs) (at 2016-05-31 05:20:58.058247) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 05:20:58.062783) ========= 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.0AYo5WJDEF/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.gswTJgJtLl/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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/Firefox/sha512/18e7e4d3df2ea2652c05c586efe93f4ba8f67fa5b494d464e75fb41d4e03d8a6e6dbcba3e565aa77568ec5f0e67c57edaac033402e91720e9b1e3077a2d8bfb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9055476330d5cb242b12bbe5ae62018fb17bc216cf9ecce824a80b4d35ffbe00cab20400abeb1ffdbbda75cf027c76f0592d127fcfde78e61838603f46f673", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0c3fd58ac1abd96464ad429aa4a321470700a1be2c8de0d72abd2b1e7b261645f43e12dd3e2137855d3a870c556a9893fa0f87ed9afec1fe6e4bf3dd45a696b"} build_url:https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010995 build_url: 'https://queue.taskcluster.net/v1/task/WqaFlwKGSi-9rnzZzJb3PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e7e4d3df2ea2652c05c586efe93f4ba8f67fa5b494d464e75fb41d4e03d8a6e6dbcba3e565aa77568ec5f0e67c57edaac033402e91720e9b1e3077a2d8bfb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a9055476330d5cb242b12bbe5ae62018fb17bc216cf9ecce824a80b4d35ffbe00cab20400abeb1ffdbbda75cf027c76f0592d127fcfde78e61838603f46f673", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0c3fd58ac1abd96464ad429aa4a321470700a1be2c8de0d72abd2b1e7b261645f43e12dd3e2137855d3a870c556a9893fa0f87ed9afec1fe6e4bf3dd45a696b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 05:20:58.099824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:20:58.100410) ========= 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.0AYo5WJDEF/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.gswTJgJtLl/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.005170 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:20:58.150970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 05:20:58.151465) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 05:20:58.152122) ========= ========= Total master_lag: 0.21 =========