builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0216 starttime: 1463630353.28 results: success (0) buildid: 20160518195600 builduid: 3fe89fe6222944938891e157dcdbccbf revision: eb54f97ddfd410d426df5989a8ddd962d97bee44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.275741) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.276163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.276441) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.296236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.296504) ========= 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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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-18 20:59:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2016-05-18 20:59:13 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162216 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.476526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.476905) ========= 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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.025758 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.525033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:13.525370) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eb54f97ddfd410d426df5989a8ddd962d97bee44 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev eb54f97ddfd410d426df5989a8ddd962d97bee44 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tdJKxOgj9b/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.NMDEYvuyfM/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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-18 20:59:13,609 truncating revision to first 12 chars 2016-05-18 20:59:13,609 Setting DEBUG logging. 2016-05-18 20:59:13,609 attempt 1/10 2016-05-18 20:59:13,609 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eb54f97ddfd4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 20:59:13,887 unpacking tar archive at: fx-team-eb54f97ddfd4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541303 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:14.084482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:14.084819) ========= script_repo_revision: eb54f97ddfd410d426df5989a8ddd962d97bee44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:14.085236) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:14.085552) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 20:59:14.100178) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-18 20:59:14.100538) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tdJKxOgj9b/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.NMDEYvuyfM/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:14 INFO - MultiFileLogger online at 20160518 20:59:14 in /builds/slave/test 20:59:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:59:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:59:14 INFO - {'append_to_log': False, 20:59:14 INFO - 'base_work_dir': '/builds/slave/test', 20:59:14 INFO - 'blob_upload_branch': 'Fx-Team', 20:59:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:59:14 INFO - 'branch': 'Fx-Team', 20:59:14 INFO - 'buildbot_json_path': 'buildprops.json', 20:59:14 INFO - 'config_files': ('talos/mac_config.py',), 20:59:14 INFO - 'default_actions': ('clobber', 20:59:14 INFO - 'read-buildbot-config', 20:59:14 INFO - 'download-and-extract', 20:59:14 INFO - 'populate-webroot', 20:59:14 INFO - 'create-virtualenv', 20:59:14 INFO - 'install', 20:59:14 INFO - 'run-tests'), 20:59:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:59:14 INFO - 'download_minidump_stackwalk': True, 20:59:14 INFO - 'download_symbols': 'ondemand', 20:59:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:59:14 INFO - 'tooltool.py': '/tools/tooltool.py', 20:59:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:59:14 INFO - '/tools/misc-python/virtualenv.py')}, 20:59:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:59:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:59:14 INFO - 'installer_path': 'installer.exe', 20:59:14 INFO - 'log_level': 'info', 20:59:14 INFO - 'log_name': 'talos', 20:59:14 INFO - 'log_to_console': True, 20:59:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:59:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:59:14 INFO - 'opt_config_files': (), 20:59:14 INFO - 'pip_index': False, 20:59:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:59:14 INFO - 'cmd': ('bash', 20:59:14 INFO - '-c', 20:59:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:59:14 INFO - 'enabled': True, 20:59:14 INFO - 'halt_on_failure': False, 20:59:14 INFO - 'name': 'check_screen_resolution'},), 20:59:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:59:14 INFO - 'cmd': ('bash', 20:59:14 INFO - '-c', 20:59:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:59:14 INFO - 'enabled': True, 20:59:14 INFO - 'halt_on_failure': False, 20:59:14 INFO - 'name': 'check_screen_resolution'},), 20:59:14 INFO - 'run_cmd_checks_enabled': True, 20:59:14 INFO - 'sps_profile': False, 20:59:14 INFO - 'sps_profile_interval': 0, 20:59:14 INFO - 'suite': 'dromaeojs', 20:59:14 INFO - 'system_bits': '32', 20:59:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:59:14 INFO - 'title': 't-yosemite-r7-0216', 20:59:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:59:14 INFO - 'use_talos_json': True, 20:59:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:59:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:59:14 INFO - 'work_dir': 'build'} 20:59:14 INFO - ##### 20:59:14 INFO - ##### Running clobber step. 20:59:14 INFO - ##### 20:59:14 INFO - Running pre-action listener: _resource_record_pre_action 20:59:14 INFO - Running main action method: clobber 20:59:14 INFO - rmtree: /builds/slave/test/build 20:59:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:59:16 INFO - Running post-action listener: _resource_record_post_action 20:59:16 INFO - ##### 20:59:16 INFO - ##### Finished clobber step (success) 20:59:16 INFO - ##### 20:59:16 INFO - ##### 20:59:16 INFO - ##### Running read-buildbot-config step. 20:59:16 INFO - ##### 20:59:16 INFO - Running pre-action listener: _resource_record_pre_action 20:59:16 INFO - Running main action method: read_buildbot_config 20:59:16 INFO - Using buildbot properties: 20:59:16 INFO - { 20:59:16 INFO - "project": "", 20:59:16 INFO - "product": "firefox", 20:59:16 INFO - "script_repo_revision": "production", 20:59:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:59:16 INFO - "repository": "", 20:59:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:59:16 INFO - "buildid": "20160518195600", 20:59:16 INFO - "pgo_build": "False", 20:59:16 INFO - "basedir": "/builds/slave/test", 20:59:16 INFO - "buildnumber": 14, 20:59:16 INFO - "slavename": "t-yosemite-r7-0216", 20:59:16 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 20:59:16 INFO - "platform": "yosemite_r7", 20:59:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:59:16 INFO - "branch": "fx-team", 20:59:16 INFO - "repo_path": "integration/fx-team", 20:59:16 INFO - "revision": "eb54f97ddfd410d426df5989a8ddd962d97bee44", 20:59:16 INFO - "stage_platform": "macosx64", 20:59:16 INFO - "builduid": "3fe89fe6222944938891e157dcdbccbf", 20:59:16 INFO - "slavebuilddir": "test" 20:59:16 INFO - } 20:59:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:59:16 INFO - Running post-action listener: _resource_record_post_action 20:59:16 INFO - ##### 20:59:16 INFO - ##### Finished read-buildbot-config step (success) 20:59:16 INFO - ##### 20:59:16 INFO - ##### 20:59:16 INFO - ##### Running download-and-extract step. 20:59:16 INFO - ##### 20:59:16 INFO - Running pre-action listener: _resource_record_pre_action 20:59:16 INFO - Running main action method: download_and_extract 20:59:16 INFO - mkdir: /builds/slave/test/build/tests 20:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:59:16 INFO - https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:59:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:59:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:59:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:17 INFO - Downloaded 1199 bytes. 20:59:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:59:17 INFO - Using the following test package requirements: 20:59:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:59:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:59:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:59:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'jsshell-mac.zip'], 20:59:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:59:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:59:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:59:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:59:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:59:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:59:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:59: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')]} 20:59:17 INFO - https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:59:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:18 INFO - Downloaded 18628920 bytes. 20:59:18 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'] 20:59:18 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 20:59:21 INFO - Return code: 0 20:59:21 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 20:59:21 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')]} 20:59:21 INFO - https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:59:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:24 INFO - Downloaded 18628920 bytes. 20:59:24 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'] 20:59:24 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 20:59:28 INFO - Return code: 0 20:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:28 INFO - https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:59:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:59:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:59:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:28 INFO - Downloaded 10840194 bytes. 20:59:28 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'] 20:59:28 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 20:59:29 INFO - Return code: 0 20:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:29 INFO - https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:59:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/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 20:59:32 INFO - Downloaded 110314542 bytes. 20:59:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:32 INFO - mkdir: /builds/slave/test/properties 20:59:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:59:32 INFO - Writing to file /builds/slave/test/properties/build_url 20:59:32 INFO - Contents: 20:59:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:33 INFO - Running post-action listener: _resource_record_post_action 20:59:33 INFO - Running post-action listener: set_extra_try_arguments 20:59:33 INFO - ##### 20:59:33 INFO - ##### Finished download-and-extract step (success) 20:59:33 INFO - ##### 20:59:33 INFO - ##### 20:59:33 INFO - ##### Running populate-webroot step. 20:59:33 INFO - ##### 20:59:33 INFO - Running pre-action listener: _resource_record_pre_action 20:59:33 INFO - Running main action method: populate_webroot 20:59:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:59:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:59:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:59:33 INFO - '--tppagecycles', 20:59:33 INFO - '7'], 20:59:33 INFO - 'tests': ['tsvgm']}}, 20:59:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:59:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:59:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:59:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:59:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:59:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:59:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['damp', 'tps']}, 20:59:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'tests': ['damp', 'tps']}, 20:59:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['dromaeo_dom']}, 20:59:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:59:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['a11yr', 20:59:33 INFO - 'ts_paint', 20:59:33 INFO - 'tpaint', 20:59:33 INFO - 'sessionrestore', 20:59:33 INFO - 'sessionrestore_no_auto_restore', 20:59:33 INFO - 'tabpaint']}, 20:59:33 INFO - 'other-e10s': {'tests': ['a11yr', 20:59:33 INFO - 'ts_paint', 20:59:33 INFO - 'tpaint', 20:59:33 INFO - 'sessionrestore', 20:59:33 INFO - 'sessionrestore_no_auto_restore', 20:59:33 INFO - 'tabpaint']}, 20:59:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:59:33 INFO - 'ts_paint', 20:59:33 INFO - 'tpaint', 20:59:33 INFO - 'sessionrestore', 20:59:33 INFO - 'sessionrestore_no_auto_restore', 20:59:33 INFO - 'tabpaint']}, 20:59:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:59:33 INFO - 'ts_paint', 20:59:33 INFO - 'tpaint', 20:59:33 INFO - 'sessionrestore', 20:59:33 INFO - 'sessionrestore_no_auto_restore', 20:59:33 INFO - 'tabpaint']}, 20:59:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['a11yr', 20:59:33 INFO - 'ts_paint', 20:59:33 INFO - 'tpaint', 20:59:33 INFO - 'sessionrestore', 20:59:33 INFO - 'sessionrestore_no_auto_restore', 20:59:33 INFO - 'tabpaint']}, 20:59:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['a11yr', 20:59:33 INFO - 'ts_paint', 20:59:33 INFO - 'tpaint', 20:59:33 INFO - 'sessionrestore', 20:59:33 INFO - 'sessionrestore_no_auto_restore', 20:59:33 INFO - 'tabpaint']}, 20:59:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['tsvgx', 20:59:33 INFO - 'tsvgr_opacity', 20:59:33 INFO - 'tart', 20:59:33 INFO - 'tscrollx', 20:59:33 INFO - 'cart']}, 20:59:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:59:33 INFO - 'tsvgr_opacity', 20:59:33 INFO - 'tart', 20:59:33 INFO - 'tscrollx', 20:59:33 INFO - 'cart']}, 20:59:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'talos_options': ['--disable-e10s'], 20:59:33 INFO - 'tests': ['tp5o']}, 20:59:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'tests': ['tp5o']}, 20:59:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'talos_options': ['--disable-e10s', 20:59:33 INFO - '--xperf_path', 20:59:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:33 INFO - 'tests': ['tp5n']}, 20:59:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:33 INFO - 'talos_options': ['--xperf_path', 20:59:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:33 INFO - 'tests': ['tp5n']}}, 20:59:33 INFO - 'talos.zip': {'path': '', 20:59:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:59:33 INFO - Running post-action listener: _resource_record_post_action 20:59:33 INFO - ##### 20:59:33 INFO - ##### Finished populate-webroot step (success) 20:59:33 INFO - ##### 20:59:33 INFO - ##### 20:59:33 INFO - ##### Running create-virtualenv step. 20:59:33 INFO - ##### 20:59:33 INFO - Running pre-action listener: _resource_record_pre_action 20:59:33 INFO - Running main action method: create_virtualenv 20:59:33 INFO - Creating virtualenv /builds/slave/test/build/venv 20:59:33 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 20:59:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:59:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:59:33 INFO - Using real prefix '/tools/python27' 20:59:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:59:34 INFO - Installing distribute.............................................................................................................................................................................................done. 20:59:37 INFO - Installing pip.................done. 20:59:37 INFO - Return code: 0 20:59:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:59:37 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')]} 20:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:37 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')]} 20:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:37 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:37 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 20:59:37 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 20:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:37 INFO - 'HOME': '/Users/cltbld', 20:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:37 INFO - 'LOGNAME': 'cltbld', 20:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:37 INFO - 'MOZ_NO_REMOTE': '1', 20:59:37 INFO - 'NO_EM_RESTART': '1', 20:59:37 INFO - 'PAGER': '/bin/cat', 20:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:37 INFO - 'PWD': '/builds/slave/test', 20:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:37 INFO - 'SHELL': '/bin/bash', 20:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:37 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:37 INFO - 'USER': 'cltbld', 20:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:37 INFO - 'XPC_FLAGS': '0x0', 20:59:37 INFO - 'XPC_SERVICE_NAME': '0', 20:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:37 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 20:59:37 INFO - Cleaning up... 20:59:37 INFO - Return code: 0 20:59:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:59:37 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')]} 20:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:37 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')]} 20:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:37 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:37 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 20:59:37 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 20:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:37 INFO - 'HOME': '/Users/cltbld', 20:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:37 INFO - 'LOGNAME': 'cltbld', 20:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:37 INFO - 'MOZ_NO_REMOTE': '1', 20:59:37 INFO - 'NO_EM_RESTART': '1', 20:59:37 INFO - 'PAGER': '/bin/cat', 20:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:37 INFO - 'PWD': '/builds/slave/test', 20:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:37 INFO - 'SHELL': '/bin/bash', 20:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:37 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:37 INFO - 'USER': 'cltbld', 20:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:37 INFO - 'XPC_FLAGS': '0x0', 20:59:37 INFO - 'XPC_SERVICE_NAME': '0', 20:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:37 INFO - Downloading/unpacking psutil>=3.1.1 20:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:59:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:59:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:59:40 INFO - Installing collected packages: psutil 20:59:40 INFO - Running setup.py install for psutil 20:59:40 INFO - building 'psutil._psutil_osx' extension 20:59:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:59:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:59:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:59:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:59:41 INFO - building 'psutil._psutil_posix' extension 20:59:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:59:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:59:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:59:41 INFO - ^ 20:59:41 INFO - 1 warning generated. 20:59:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:59:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:59:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:59:41 INFO - Successfully installed psutil 20:59:41 INFO - Cleaning up... 20:59:41 INFO - Return code: 0 20:59:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:41 INFO - 'HOME': '/Users/cltbld', 20:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:41 INFO - 'LOGNAME': 'cltbld', 20:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:41 INFO - 'MOZ_NO_REMOTE': '1', 20:59:41 INFO - 'NO_EM_RESTART': '1', 20:59:41 INFO - 'PAGER': '/bin/cat', 20:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:41 INFO - 'PWD': '/builds/slave/test', 20:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:41 INFO - 'SHELL': '/bin/bash', 20:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:41 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:41 INFO - 'USER': 'cltbld', 20:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:41 INFO - 'XPC_FLAGS': '0x0', 20:59:41 INFO - 'XPC_SERVICE_NAME': '0', 20:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:59:42 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 20:59:42 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 20:59:42 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 20:59:42 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 20:59:42 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 20:59:42 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 20:59:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:59:44 INFO - Installing collected packages: mozsystemmonitor 20:59:44 INFO - Running setup.py install for mozsystemmonitor 20:59:44 INFO - Successfully installed mozsystemmonitor 20:59:44 INFO - Cleaning up... 20:59:44 INFO - Return code: 0 20:59:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:44 INFO - 'HOME': '/Users/cltbld', 20:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:44 INFO - 'LOGNAME': 'cltbld', 20:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:44 INFO - 'MOZ_NO_REMOTE': '1', 20:59:44 INFO - 'NO_EM_RESTART': '1', 20:59:44 INFO - 'PAGER': '/bin/cat', 20:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:44 INFO - 'PWD': '/builds/slave/test', 20:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:44 INFO - 'SHELL': '/bin/bash', 20:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:44 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:44 INFO - 'USER': 'cltbld', 20:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:44 INFO - 'XPC_FLAGS': '0x0', 20:59:44 INFO - 'XPC_SERVICE_NAME': '0', 20:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:44 INFO - Downloading/unpacking blobuploader==1.2.4 20:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:47 INFO - Downloading blobuploader-1.2.4.tar.gz 20:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:59:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:59:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:48 INFO - Downloading docopt-0.6.1.tar.gz 20:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:59:48 INFO - Installing collected packages: blobuploader, requests, docopt 20:59:48 INFO - Running setup.py install for blobuploader 20:59:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:59:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:59:48 INFO - Running setup.py install for requests 20:59:49 INFO - Running setup.py install for docopt 20:59:49 INFO - Successfully installed blobuploader requests docopt 20:59:49 INFO - Cleaning up... 20:59:49 INFO - Return code: 0 20:59:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:49 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:49 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 20:59:49 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 20:59:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:49 INFO - 'HOME': '/Users/cltbld', 20:59:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:49 INFO - 'LOGNAME': 'cltbld', 20:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:49 INFO - 'MOZ_NO_REMOTE': '1', 20:59:49 INFO - 'NO_EM_RESTART': '1', 20:59:49 INFO - 'PAGER': '/bin/cat', 20:59:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:49 INFO - 'PWD': '/builds/slave/test', 20:59:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:49 INFO - 'SHELL': '/bin/bash', 20:59:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:49 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:49 INFO - 'USER': 'cltbld', 20:59:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:49 INFO - 'XPC_FLAGS': '0x0', 20:59:49 INFO - 'XPC_SERVICE_NAME': '0', 20:59:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:59:49 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-rJGgCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:59:49 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-dMp4wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:59:49 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-_iI3iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-9a6J3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-DfOJJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-kvqDP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-FqbAhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-NwX69_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-bUkXBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-B3Hkjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-5XjUmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:59:50 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Wxwk6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:59:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-WuBa4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:59:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-kw3eu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-SYbFjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:59:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Fth6YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:59:51 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-2svY_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:59:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:59:51 INFO - Running setup.py install for manifestparser 20:59:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:59:51 INFO - Running setup.py install for mozcrash 20:59:51 INFO - Running setup.py install for mozdebug 20:59:51 INFO - Running setup.py install for mozdevice 20:59:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Running setup.py install for mozfile 20:59:52 INFO - Running setup.py install for mozhttpd 20:59:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Running setup.py install for mozinfo 20:59:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Running setup.py install for mozInstall 20:59:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:59:52 INFO - Running setup.py install for mozleak 20:59:52 INFO - Running setup.py install for mozlog 20:59:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:59:53 INFO - Running setup.py install for moznetwork 20:59:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:59:53 INFO - Running setup.py install for mozprocess 20:59:53 INFO - Running setup.py install for mozprofile 20:59:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:59:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:59:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:59:53 INFO - Running setup.py install for mozrunner 20:59:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:59:53 INFO - Running setup.py install for mozscreenshot 20:59:53 INFO - Running setup.py install for moztest 20:59:53 INFO - Running setup.py install for mozversion 20:59:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:59:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:59:54 INFO - Cleaning up... 20:59:54 INFO - Return code: 0 20:59:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20: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')]} 20:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20: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')]} 20:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:54 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:54 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 20:59:54 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 20:59:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:54 INFO - 'HOME': '/Users/cltbld', 20:59:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:54 INFO - 'LOGNAME': 'cltbld', 20:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:54 INFO - 'MOZ_NO_REMOTE': '1', 20:59:54 INFO - 'NO_EM_RESTART': '1', 20:59:54 INFO - 'PAGER': '/bin/cat', 20:59:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:54 INFO - 'PWD': '/builds/slave/test', 20:59:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:54 INFO - 'SHELL': '/bin/bash', 20:59:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:54 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:54 INFO - 'USER': 'cltbld', 20:59:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:54 INFO - 'XPC_FLAGS': '0x0', 20:59:54 INFO - 'XPC_SERVICE_NAME': '0', 20:59:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:59:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-8XavVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:59:54 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)) 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:59:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XZhTyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:59:54 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)) 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:59:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-9xveR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:59:54 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)) 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:59:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-OLR9kR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:59:54 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)) 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:59:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-coP916-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:59:54 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)) 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:59:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-t67FBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-WAvNNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-SVF175-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-hpq7q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-9k8P6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-v_aTbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-6IZ9Sl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-GL7KOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-yYiOdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-BKn7Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:55 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)) 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:59:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-RhJhDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:59:56 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)) 20:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:59:56 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-pyrtPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:59:56 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)) 20:59:56 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)) 20:59:56 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)) 20:59:56 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)) 20:59:56 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)) 20:59:56 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)) 20:59:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:59:56 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 20:59:56 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 20:59:56 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 20:59:56 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 20:59:56 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 20:59:56 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 20:59:58 INFO - Downloading blessings-1.6.tar.gz 20:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:59:58 INFO - Installing collected packages: blessings 20:59:58 INFO - Running setup.py install for blessings 20:59:58 INFO - Successfully installed blessings 20:59:58 INFO - Cleaning up... 20:59:59 INFO - Return code: 0 20:59:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:59:59 INFO - Reading from file tmpfile_stdout 20:59:59 INFO - Current package versions: 20:59:59 INFO - blessings == 1.6 20:59:59 INFO - blobuploader == 1.2.4 20:59:59 INFO - docopt == 0.6.1 20:59:59 INFO - manifestparser == 1.1 20:59:59 INFO - mozInstall == 1.12 20:59:59 INFO - mozcrash == 0.17 20:59:59 INFO - mozdebug == 0.1 20:59:59 INFO - mozdevice == 0.48 20:59:59 INFO - mozfile == 1.2 20:59:59 INFO - mozhttpd == 0.7 20:59:59 INFO - mozinfo == 0.9 20:59:59 INFO - mozleak == 0.1 20:59:59 INFO - mozlog == 3.2 20:59:59 INFO - moznetwork == 0.27 20:59:59 INFO - mozprocess == 0.22 20:59:59 INFO - mozprofile == 0.28 20:59:59 INFO - mozrunner == 6.11 20:59:59 INFO - mozscreenshot == 0.1 20:59:59 INFO - mozsystemmonitor == 0.3 20:59:59 INFO - moztest == 0.7 20:59:59 INFO - mozversion == 1.4 20:59:59 INFO - psutil == 3.1.1 20:59:59 INFO - requests == 1.2.3 20:59:59 INFO - wsgiref == 0.1.2 20:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20: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')]} 20:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20: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')]} 20:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:59 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:59 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 20:59:59 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 20:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:59 INFO - 'HOME': '/Users/cltbld', 20:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:59 INFO - 'LOGNAME': 'cltbld', 20:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:59 INFO - 'MOZ_NO_REMOTE': '1', 20:59:59 INFO - 'NO_EM_RESTART': '1', 20:59:59 INFO - 'PAGER': '/bin/cat', 20:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:59 INFO - 'PWD': '/builds/slave/test', 20:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:59 INFO - 'SHELL': '/bin/bash', 20:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:59 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:59 INFO - 'USER': 'cltbld', 20:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:59 INFO - 'XPC_FLAGS': '0x0', 20:59:59 INFO - 'XPC_SERVICE_NAME': '0', 20:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 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)) 20:59:59 INFO - Cleaning up... 20:59:59 INFO - Return code: 0 20:59:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20: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')]} 20:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20: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')]} 20:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:59 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 20:59:59 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 20:59:59 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 20:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 20:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:59 INFO - 'HOME': '/Users/cltbld', 20:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:59 INFO - 'LOGNAME': 'cltbld', 20:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:59 INFO - 'MOZ_NO_REMOTE': '1', 20:59:59 INFO - 'NO_EM_RESTART': '1', 20:59:59 INFO - 'PAGER': '/bin/cat', 20:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:59 INFO - 'PWD': '/builds/slave/test', 20:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:59 INFO - 'SHELL': '/bin/bash', 20:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 20:59:59 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 20:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:59 INFO - 'USER': 'cltbld', 20:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:59 INFO - 'XPC_FLAGS': '0x0', 20:59:59 INFO - 'XPC_SERVICE_NAME': '0', 20:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:59 INFO - Downloading/unpacking jsonschema 20:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:00:02 INFO - Downloading/unpacking functools32 (from jsonschema) 21:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:02 INFO - Downloading functools32-3.2.3-2.tar.gz 21:00:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:00:02 INFO - warning: no files found matching '*.txt' 21:00:02 INFO - no previously-included directories found matching 'build' 21:00:02 INFO - no previously-included directories found matching 'dist' 21:00:02 INFO - no previously-included directories found matching '.git*' 21:00:02 INFO - Installing collected packages: jsonschema, functools32 21:00:02 INFO - Running setup.py install for functools32 21:00:02 INFO - warning: no files found matching '*.txt' 21:00:02 INFO - no previously-included directories found matching 'build' 21:00:02 INFO - no previously-included directories found matching 'dist' 21:00:02 INFO - no previously-included directories found matching '.git*' 21:00:02 INFO - Successfully installed jsonschema functools32 21:00:02 INFO - Cleaning up... 21:00:02 INFO - Return code: 0 21:00:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00:02 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 0x105f6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0e9d9cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, '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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 21:00:02 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 21:00:02 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 21:00:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 21:00:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:02 INFO - 'HOME': '/Users/cltbld', 21:00:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:02 INFO - 'LOGNAME': 'cltbld', 21:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:02 INFO - 'MOZ_NO_REMOTE': '1', 21:00:02 INFO - 'NO_EM_RESTART': '1', 21:00:02 INFO - 'PAGER': '/bin/cat', 21:00:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:02 INFO - 'PWD': '/builds/slave/test', 21:00:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:02 INFO - 'SHELL': '/bin/bash', 21:00:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 21:00:02 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 21:00:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:02 INFO - 'USER': 'cltbld', 21:00:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:02 INFO - 'XPC_FLAGS': '0x0', 21:00:02 INFO - 'XPC_SERVICE_NAME': '0', 21:00:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:03 INFO - Downloading/unpacking flake8 21:00:03 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 21:00:03 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 21:00:03 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 21:00:03 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 21:00:03 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 21:00:03 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 21:00:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:00:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:00:05 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 21:00:05 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 21:00:05 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 21:00:05 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 21:00:05 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 21:00:05 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 21:00:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:00:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:00:07 INFO - no previously-included directories found matching 'docs/_build' 21:00:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:00:07 INFO - Running setup.py install for pep8 21:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:00:07 INFO - no previously-included directories found matching 'docs/_build' 21:00:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:00:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:00:07 INFO - Cleaning up... 21:00:07 INFO - Return code: 0 21:00:07 INFO - Running post-action listener: _resource_record_post_action 21:00:07 INFO - Running post-action listener: _start_resource_monitoring 21:00:07 INFO - Starting resource monitoring. 21:00:07 INFO - ##### 21:00:07 INFO - ##### Finished create-virtualenv step (success) 21:00:07 INFO - ##### 21:00:07 INFO - ##### 21:00:07 INFO - ##### Running install step. 21:00:07 INFO - ##### 21:00:07 INFO - Running pre-action listener: _resource_record_pre_action 21:00:07 INFO - Running main action method: install 21:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:00:07 INFO - Reading from file tmpfile_stdout 21:00:07 INFO - Detecting whether we're running mozinstall >=1.0... 21:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:00:07 INFO - Reading from file tmpfile_stdout 21:00:07 INFO - Output received: 21:00:07 INFO - Usage: mozinstall [options] installer 21:00:07 INFO - Options: 21:00:07 INFO - -h, --help show this help message and exit 21:00:07 INFO - -d DEST, --destination=DEST 21:00:07 INFO - Directory to install application into. [default: 21:00:07 INFO - "/builds/slave/test"] 21:00:07 INFO - --app=APP Application being installed. [default: firefox] 21:00:07 INFO - mkdir: /builds/slave/test/build/application 21:00:07 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'] 21:00:07 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 21:00:42 INFO - Reading from file tmpfile_stdout 21:00:42 INFO - Output received: 21:00:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:00:42 INFO - Running post-action listener: _resource_record_post_action 21:00:42 INFO - ##### 21:00:42 INFO - ##### Finished install step (success) 21:00:42 INFO - ##### 21:00:42 INFO - ##### 21:00:42 INFO - ##### Running run-tests step. 21:00:42 INFO - ##### 21:00:42 INFO - Running pre-action listener: _resource_record_pre_action 21:00:42 INFO - Running main action method: run_tests 21:00:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:00:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:00:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:00:42 INFO - 2016-05-18 21:00:42.042 screenresolution[1654:7457] starting screenresolution argv=screenresolution get 21:00:42 INFO - 2016-05-18 21:00:42.044 screenresolution[1654:7457] Display 0: 1600x1200x32@60 21:00:42 INFO - 2016-05-18 21:00:42.050 screenresolution[1655:7459] starting screenresolution argv=screenresolution list 21:00:42 INFO - Available Modes on Display 0 21:00:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:00:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:00:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:00:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:00:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:00:42 INFO - Intel Iris: 21:00:42 INFO - Chipset Model: Intel Iris 21:00:42 INFO - Type: GPU 21:00:42 INFO - Bus: Built-In 21:00:42 INFO - VRAM (Dynamic, Max): 1536 MB 21:00:42 INFO - Vendor: Intel (0x8086) 21:00:42 INFO - Device ID: 0x0a2e 21:00:42 INFO - Revision ID: 0x0009 21:00:42 INFO - Displays: 21:00:42 INFO - Display: 21:00:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:00:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:00:42 INFO - Display Serial Number: 1600x1200 60 21:00:42 INFO - Main Display: Yes 21:00:42 INFO - Mirror: Off 21:00:42 INFO - Online: Yes 21:00:42 INFO - Rotation: Supported 21:00:42 INFO - Return code: 0 21:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:00:42 INFO - Python 2.7.3 21:00:42 INFO - Return code: 0 21:00:42 INFO - grabbing minidump binary from tooltool 21:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:00:42 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 0x105e13d30>, '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 0x105c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e0ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:00:42 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 21:00:42 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 21:00:42 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 21:00:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:00:42 INFO - Return code: 0 21:00:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:00:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:00:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:00:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:00:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:00:43 INFO - Return code: 0 21:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 21:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0216 --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 21:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tdJKxOgj9b/Render', 21:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:43 INFO - 'HOME': '/Users/cltbld', 21:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:43 INFO - 'LOGNAME': 'cltbld', 21:00:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:00:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:43 INFO - 'MOZ_NO_REMOTE': '1', 21:00:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:00:43 INFO - 'NO_EM_RESTART': '1', 21:00:43 INFO - 'PAGER': '/bin/cat', 21:00:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:43 INFO - 'PWD': '/builds/slave/test', 21:00:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:43 INFO - 'SHELL': '/bin/bash', 21:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NMDEYvuyfM/Listeners', 21:00:43 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 21:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:43 INFO - 'USER': 'cltbld', 21:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:43 INFO - 'XPC_FLAGS': '0x0', 21:00:43 INFO - 'XPC_SERVICE_NAME': '0', 21:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 21:00:43 INFO - mozversion application_buildid: 20160518195600 21:00:43 INFO - mozversion application_changeset: eb54f97ddfd410d426df5989a8ddd962d97bee44 21:00:43 INFO - mozversion application_display_name: Nightly 21:00:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:00:43 INFO - mozversion application_name: Firefox 21:00:43 INFO - mozversion application_remotingname: firefox 21:00:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:00:43 INFO - mozversion application_vendor: Mozilla 21:00:43 INFO - mozversion application_version: 49.0a1 21:00:43 INFO - mozversion platform_buildid: 20160518195600 21:00:43 INFO - mozversion platform_changeset: eb54f97ddfd410d426df5989a8ddd962d97bee44 21:00:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:00:43 INFO - mozversion platform_version: 49.0a1 21:00:43 INFO - using testdate: 1463630443 21:00:43 INFO - actual date: 1463630443 21:00:43 INFO - starting webserver on 'localhost:49278' 21:00:43 INFO - SUITE-START | Running 2 tests 21:00:43 INFO - TEST-START | dromaeo_css 21:00:43 INFO - operating with platform_type : mac_ 21:00:43 INFO - Initialising browser for dromaeo_css test... 21:00:43 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpmENiR2/profile http://localhost:49278/getInfo.html) 21:00:46 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 21:00:46 INFO - PROCESS | 1673 | colorDepth:24 21:00:46 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 21:00:46 INFO - PROCESS | 1673 | __metrics 21:00:46 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:00:46 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:00:47 INFO - TEST-INFO | 1673: exit 0 21:00:47 INFO - Browser initialized. 21:00:47 INFO - Running cycle 1/1 for dromaeo_css test... 21:00:47 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpmENiR2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:00:58 INFO - PROCESS | 1676 | RSS: Main: 239165440 21:00:58 INFO - PROCESS | 1676 | 21:01:51 INFO - PROCESS | 1676 | 1463630511389 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] 21:04:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 21:04:01 INFO - PROCESS | 1676 | RSS: Main: 342913024 21:04:01 INFO - PROCESS | 1676 | 21:07:05 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 21:07:05 INFO - PROCESS | 1676 | RSS: Main: 372789248 21:07:05 INFO - PROCESS | 1676 | 21:10:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 21:10:08 INFO - PROCESS | 1676 | RSS: Main: 369807360 21:10:08 INFO - PROCESS | 1676 | 21:13:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 21:13:11 INFO - PROCESS | 1676 | RSS: Main: 368902144 21:13:11 INFO - PROCESS | 1676 | 21:16:14 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 21:16:15 INFO - PROCESS | 1676 | RSS: Main: 340078592 21:16:15 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 21:19:18 INFO - PROCESS | 1676 | RSS: Main: 376475648 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | __start_tp_report 21:19:18 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 21:19:18 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 21:19:18 INFO - PROCESS | 1676 | |i|pagename|runs| 21:19:18 INFO - PROCESS | 1676 | |0;dojo.html;3338.465845464726;3408.6932176104146;3423.931521369574;3458.4293591557393;3471.878484253051;5567.47109024642;5582.19058236556;5597.888042239153;5643.971780141099;5711.3431955325095;5598.888022239563;5690;5692.772289108441;5692.829215123553;5693.373728889819;5119.308893299403;5137.871553211181;5145.999999999981;5194.870128246786;5267.288915996349;5151.047056294578;5177.974110129444;5215.765290561933;5236.31927849379;5245.580353571724;5207.635465517396;5263.605229607776;5274;5274.92087618686;5327.280817089682;988.9332713059573;5073.797048118071;5144.717040562768;5193;5210.270562121306;5092.567131793802;5150.201718733602;5175.353080864892;5180.715060671661;5183.714895680736;5107.693538387709;5379.865503362447;5515.117581186991;5546.639468434539;5687;6030.185924900126;6053.783739359561;6099.847503812396;6109.105395513301;6114.633122012693;5413.729313534308;6218.19163508741;6244.718987645567;6249.437550620466;6253.499720022387;5752.050911599581;5758.510526605201;5784.768609255667;5792.275965504312;5796.971015144897;6239.844003899939;6259.937400625936;6305.243370795442;6312.368763123743;6343.619382837044;6078.05534108743;6096.600930733159;6101.932161871655;6102.050500761722;6106.236720409949;6170.475509581646;6226.003839385677;6226.875462490725;6229.844253893689;6234.750609975641;5990.101484777319;6058.818235452944;6111.908321375183;6130.540209484307;6190.442860142609;6183.474404675653;6210.7515699371515;6221.595596286227;6221.844453888599;6228.408301211378;4899.926501102486;4951.529604687549;4956.157453232959;4970.403551573834;4984.476629953875;4362.934555981663;4387.451568553931;4429.911401771947;4436.689431739747;4472.9552704472535;5593.272874526367;5602.719864006784;5625.46558076982;5627.52166065889;5637.069883469182;6196.256449226122;6227.626342419469;6238.594491358047;6284.4344009039405;6298.244210694655;5479.726013699299;5496.5053145217125;5518.779248830082;5575.888482230333;5628.183913332544;5311.760970756325;5319.069162896478;5327.573794096463;5353.437889021675;5375.5162035417;6288.968555157195;6303.621782693053;6306.3378345274;6314.652694101868;6321.936780632135;6096.919795645128;6101.29835068955;6124.785632502841;6156.353582873734;6200.782972595937;6300.684965751602;6332.936670633235;6334.208223972004;6336.20797400325;6355.586886852339;6090.3605121461605;6134.5705800595315;6139.6009259398;6143.447089761943;6172.259328880384;4596.908061838812;6100.725467354068;6112.480439162543;6128.356522565066;6164.7842325518395;5966.552508561788;5967.552433567586;5971.940280597312;5972.492338151305;5982.790602328898;5946.583739138392;5982.042873140276;5982.252218472691;5985.132155837467;5992.64044157352;4268.59448352406;4920.803167873245;5731.398203188772;5780.959427303126;5854.853628659318;6017.699115044318;6051.262668961075;6054.152418661479;6065.332813390612;6125.356837532173;5723.313202415737;5865.5600829937075;5868.970655146697;5874.206982057539;5875 21:19:18 INFO - PROCESS | 1676 | |1;ext.html;486.2414633172254;495.1137463939548;509.9490050994901;517.7540668182617;536.4742552298748;25129.623055654156;42999.78500107499;43924;43925.12149757011;44149.33775993354;45434.77282613591;45625.31562026572;45723.54276457235;45753.62739117832;46026.76986615071;8646.956765216197;9659.130678238975;9876.308658393915;9881.95059024704;9894.604215831358;8695.95652021739;8806.691765788199;8821;8833;11680.824787628193;11660.883391166108;11905.70235744109;11919.94040029803;11930.284182949008;11960.222585531912;10427.895721042769;11447.969682728595;11727.824082638766;11851.703707407298;11874.762504749942;8634.352423568258;11472.541098356056;11671.824922626167;11799.882001179963;11805.114616403804;41505.584944150476;41832.790836045926;41984.370234446505;42027.159456811;42099.368509472384;3269.836508174582;3326.185084654275;3374.6119196292493;3476.495908093312;3709.981450092759;23644.763552364428;28797.85601072002;29419;29589.70410295891;31114.910978115884;2702.27038699552;2713.186044186736;2737.4798788230173;2742.3829638331417;2750.876210570509;515089;516576;516994;517674.4116279432;518820;63804.68097659482;64220.67889660615;64231;64289.67855160694;64324;67801;67886;67904.6604766973;67957;68083.65958170178;16503.587410314838;17194.398196063077;17243.913780431267;17251.74122388165;17285.222165002604;12132;12286.631401057983;12359.32023738703;12569.371531423576;12600.936995315147;17259.568510787332;17399.391021314193;17601.471955841345;17651.73522397165;17899.10504474771;6440.903386449207;6456.289808121103;6465.741370345134;6505.381988711065;6522.771228242051;891.9821603567892;993.7515621094726;1001.7846163074828;1027.5786927359748;1034.4362322534087;25118;30206.697933020387;30376.93680721164;30585.84707076495;30652.08043758691;1030.1707125763683;1067.3275836223129;1069.395791377869;1144.856892888389;1146.8451759012594;13574.524891628746;15604.531864043869;15629;15675.294611742498;16031;514011;602044.9897750571;613638;619961;621126;62358.37641623616;62583.06125408031;62744;62789.37210627927;62790;1274.6239859241546;1395.5883014510744;1402.6633607933818;1415.7380884536394;1453.026472263586;31043.689563104985;31065.689343106278;31160;31782.68217317797;31895.202619934687;53706.19440708471;54409;54497.18254226111;54591.72704136454;54667.45332546624;533166;534956;535482;536201;536346;34102.82948585241;40824.1835163301;42591.7870410646;43972.340414893166;44677.55322446734;46662.53337466582;48281.03437931152;48323.75838120928;48519.757401212766;49314;37103.44344834776;38488.422673660476;38496;39627;40188.79905600454;37748;38280.42579361368;38283.61716382801;38922.2215555693;39195.608043919194;38159.236815262986;38430.03924901788;38845.417318739674;39295;39358;32123.51814722828;33601.495977560844;34177;34189.14527136742;34206.315873682885;21544.569108618052;24852.25443236706;24992;25516.362090947874;25669.871650642373 21:19:18 INFO - PROCESS | 1676 | |2;jquery.html;384.6711062041954;388.21192978254135;395.15239810606266;398.51978366068874;418.53751604477054;20046.99765011749;20262.290819821308;21264.362069137933;21316.46708832278;21350.679739803876;20505.872177030258;20944.476388090305;22272.331830045125;22557.210497632586;22607;6075.483583895374;6370.649614271213;6400.199975003125;6420.871582568346;6421.486281097501;6206.689665516728;6266.655333956631;6266.968665156668;6289.716962736665;6294.2761582417925;6020.157177995085;6239.750409983595;6247.500199983991;6256.155419018442;6283.622982621035;6093.78671746489;6199.907001394983;6233.812985610393;6243.125962365295;6258.780942667007;5169.991851588941;5233.921491177635;5283.682979021271;5301.602379821529;5322.494363035506;24448.755512444826;25543;26023.479530409477;26888.59667104995;26909.865450672816;2161.438026113206;2269.1150451323997;2395.7843794058617;2505.08564374004;2601.687797464316;10304.227182961307;10325.48372581368;10434.634787782392;10666.146708263397;10724.302920310154;1748.2919417635878;1820.0990509697651;1829.1128802530761;1837.8529717622557;1855.5824939388665;122191;134293;135191.999999999;135624.32187839097;136182;11928.642140735792;11933.88066119345;11939.761384708303;11975.700607484709;11980.161388702707;15537.766933496006;15567.766483502757;15585.610359741097;15603.219839008005;15712.60718482047;8901.688440904538;8919.866202006975;8950.462972221558;8979.595918183697;9057.456552606733;8772.38593298463;8789.033206347296;8812.206901378779;8944.642214311485;8962.417442866194;4107.363358679409;4683.274092515666;4728.148933192058;4746.786394612251;4787.234042553245;1797.4901083090006;1961.3331467301186;1967.9606407871763;1968.2149758092426;2002.859799813999;715.1668306423063;806.681360862456;866.9089745576749;964.320154291237;1001.6744558018528;16642.75035874463;20652.17391304331;21085.78914210869;21206.681899771516;21250.149994000378;923.7921467669721;941.3970371921642;961.2022021721954;972.4700038478904;980.764616492051;11137.387443690675;11552.537898484135;11579.478923448465;11623;11630.825537616773;602128;618972;619550;630176;638320;67661;78637;113519.43240283911;138664;149041.76437353448;977.4428575711918;1017.4963393120305;1039.0440794469107;1040.0119886108075;1040.484959944817;18842.623147537248;19227.423177304703;19372;19389.418317450498;19451.735637183538;36696.94500407403;46002.769986149855;51367;53169;53396.73301633467;79556.60221698854;81550;83002.3399531994;83374;83537.58231209048;8091.595420228848;8279.291287873086;8421.15788421153;8434.957825210835;8554.657813687632;9256.95371523138;12066.698332541406;12683.11218214716;13165.60503184906;13172.60482185536;10972.615958441416;10987.725306867073;11154.888451115385;11348.665318251187;11430.885691143314;9233.67682131149;9554.761130971781;10641.840372394265;11116.444177790916;11282.323060616389;11020.779584408107;11406.54373825071;11442.885571144516;11613.129015323715;11721.706957326134;8160.5103693778565;8372.874406884024;8394.286485648545;8423.8736418955;8656.610452529525;9922.751931201778;10912.672619821418;11422.82865757031;11444.82832757526;11552.537898483966 21:19:18 INFO - PROCESS | 1676 | |3;mootools.html;1360.129517109051;1623.7239669256226;1628.9755653665195;1879.1731638079243;1990.3531352310495;8225.670973161074;8883.689070882521;9075.455472671645;9103.271738260939;9234.261259099272;8736.519491427985;8990.190882820545;9017.098290170996;9024.909750902489;9035.457872527653;2214.6235140026156;2296.242240060776;2299.620562607168;2491.9750802492017;2527.8736063196857;2485.2047344849657;2573.8841752121107;2576.8325058871205;2583.0959164292535;2584.741525847419;2468.5309791139716;2530.6204069389646;2534.581794003987;2547.7579629935126;2554.3869471326934;2199.3182113544854;2461.384653836541;2517.1944977607172;2551.5407226699185;2558.4499332643477;2533.6199570064455;2563.9871800640876;2567.87160641969;2569.203546900467;2573.7812285955715;9183.770405739842;9219.631214751402;9223.584938677775;9229.307801914883;9278.768030799216;5488.69812160331;5673.574481913832;5675.801346952878;5684.943150568482;5692.37383887772;3554.182538016245;3597.94603080954;3744.326021316162;3783.5838057813617;3803.410471376941;1066.8773091094547;1067.5783065689009;1069.03786592067;1073.2111897755144;1080.5947769586405;12529.248245105322;12570.68573285666;12574.81137782934;12574.937125314314;12590.37048147598;4962.553370196628;5078.873028174251;5136.8715782105;5156.123459011977;5156.716380599103;10525.526351314209;10633.989770971746;10634.149268058536;10641;10649.988251116132;5717.342505611825;5718.79984200551;5722.2561067061015;5722.484976352148;5767.711614419262;4261.829526818892;4506.355180359145;5068.189089745623;5188.118019936544;5226.216067589892;4175.478065241845;4210.200061988213;4244.193603215441;4278.165757677223;4295.935560966588;1349.3185941099682;1372.8078069070339;1373.3108276819569;1384.6607581142684;1386.6757246829254;886.3795343259615;921.7833809054866;935.4574346878794;950.1163917556738;969.5152423788106;6061.969690151521;6105.633661980295;6111.8472038198515;6167.506599472032;6284.057391391328;463.5480406603602;469.56565177210945;474.84804862443684;476.37356875709025;486.7468916163575;3920.725549211528;4052.371882358239;4196.328587425997;4201.915961680749;4211.620954114144;11096.61161859331;11116.61091861781;11568.537258509734;11593.536258549731;11596;11315.717107072389;11510;11536.480858361227;11547.538098476134;11566.016888564398;8151.470154439942;8560.914390856136;8575.442596231349;8615.181557752003;8693.608787604573;4799.208130658542;6080.422359875804;6125.754969801158;6266.467350275278;6322.0516922462;10347.63783267582;10638.574457021632;10675.946620266848;10694.89305106939;10701.571937122428;9731.56207970629;10043.949780251052;10105.44420056904;10598.735031623963;10618.575256989634;7741.535507869546;8127.79680507992;8415.915840841513;8657.870131948152;8662.35032372562;8782;8926.464412135292;8929.910700892908;8937.59780809878;8945.821083578423;7738.419618528746;7886.171951944963;7909;8061.31478824283;8083.555404452812;7600.80997975055;7729.613519323899;7755.108162561152;7851.489653172525;7906.960465197867;7265.782026539212;7392.482526223328;7405.888911666221;7415.221401752954;7427.962860185665;4892.902141957212;5105;5105.081085404663;5172.7155006475;5210.739463026909;5207.739613019409;5217.582593392595;5261.921071184011;5402.135658294654;5488.0395930711165 21:19:18 INFO - PROCESS | 1676 | |4;prototype.html;283.22537858955764;310.3730464461787;314.79066420830145;325.1027165024532;332.0072981784465;4362.996510802517;5275.868103297419;5425.891482170359;5451.945480545193;5467.835964921053;4279.282655513748;5319.468053194679;5347.171188465784;5368.597355198366;5404.1083221268445;4144.8549300774475;4145.751254924707;4153.7300075494995;4163.354680024601;4200.222958752625;4156.896077598054;4170.270202714512;4175.06061136245;4178.937315940263;4181.728187667807;3158.5578019077348;3489.185623475791;3817.331966905794;3966.3653815389544;3970.483837101173;3474.409350410436;3507.3862074136923;3805.41016142497;4083.5508094109623;4093.5906409358977;3157.295060667237;3574.6782789549065;3582.247727977128;3585.0678823505814;3597.892063238081;4768.427788665348;4802.5197480252045;5130.358705161855;5218.321618189649;5242.68543887368;3803.220339830328;3891.5524714657895;4225.68307376948;4562.038323520885;4681.836135735233;4681.836135735233;5237.423883372826;5641;5751.181920858791;5753.654780713171;3634.582023067355;3878.806059697004;3891.3773796192754;3900.726949113535;3922.37242041272;5739.368669446358;5909.409059094056;6018.608790428608;6032.66820324882;6067.332593414721;3850.4994350734787;3928.448262936124;3934.6458818706456;3934.9213015740097;3937.488126543531;3812.6187381262207;4000.399940009022;4002.299022915249;4037.0016841663564;4079.5920407958965;4062.988820533855;4115;4191.371294305817;4590.87523556724;4694.460137084279;5210.869728256824;5278.947210527922;5708.1723150143;5815.709214539256;5840.649561026352;1333.2200662612481;1363.073110284996;1372.3344178073626;1380.0477670407388;1385.4042761612402;827.668932427022;845.6174155256077;849.7042010933376;866.7399780065955;925.3337596930199;4694.976525117421;4735.981763920774;4874.781304673832;5209.114450543417;5266.552343050884;5316.255724198615;5447.101228297366;5741.770329186786;5787.305523337226;5789.392113828072;4367.541408152162;4420.756858372821;4897.657163998486;5222.112240919053;5316.580955665163;2740.862542045069;2971.658259300165;3084.8457577121053;3088.5212792017273;3105.2702614885484;5625;5683.829485115453;5896.675682837399;6049.304330002018;6109.6639684817765;5313.017091838023;5642.463965923313;5660.603757737002;5973.253343332083;6032.849178770566;5057.949420505822;5397.676139431568;5758.236046697609;6043.909341359883;6099.817005489843;5492.588055895744;5541.501264886179;5832.795852145155;5841.575746065335;5849.970750146393;4598.471175814824;5511.437181710524;5764.279465066867;5765.7117144141785;5776;5140.843310255163;5923.585349025527;5958.599729063753;6034.969825150846;6047.123167140652;3889.611038896088;3892.8832135035996;3912.9630647878903;3916.5104361954754;3923.7449565778134;5135.435102138837;5155.613329000265;5318.627696061239;5864.501517371071;5883.823485295448;5316.059638232095;5407.864803379947;5665.773369065191;5697.857553561194;5727.255456790591;4845.563899249085;5112.821051263337;5640.520555752807;5735.684537350319;5742.28221472316;5080.974595127001;5315.883664430513;5472.157140930226;5567.387587365468;5579.637323574117;4825.214670571911;5114.795408183631;5139.665921715226;5181.326427564392;5492.423295554069;5369.1140961740875;5454.072807622634;5619.381867994599;5621.695105006927;5641.294838145232 21:19:18 INFO - PROCESS | 1676 | |5;yui.html;630.8454428664978;700.8843540815766;730.52150841199;734.5886303669948;742.6212631557905;14658.413663453463;14951.401943922257;15442.459513917016;15451.922740386297;15497.225138743073;14061.296935153252;14209.289535523209;14238;14255.287235638229;14324.068935519184;717.0964584623385;722.9746958856441;894.1862904756673;908.1191244492865;931.16660588773;907.0158877617793;914.7255823253031;916.3860213656833;917.7843206846386;921.5023887100957;964.4840010594334;965.6378857928277;965.7392504023909;966.6906589891238;969.1616751509964;738.896554482373;933.2533972821716;942.1097063275207;946.3281070439996;959.0265880131637;721.1490441279288;722.1190148019408;723.0925188887912;724.0912654618472;724.478375569589;14880.330385132695;14887;14888.255587220596;14936.62658433537;14953.476628317956;11847.881521184763;12141.817872731915;12253.48251887664;12339.074569407241;12541.372931353395;10767.730806729816;10965.725856853564;11102.666919992413;11572.189946703735;11595.710107247301;3500.9649903500895;3971.721979461439;4082.265192265391;4173.31140361843;4187.350960601112;75095.62452187759;75772;76031.61984190099;77438.6128069356;78161;7763.417743669134;9387.483688397067;9525.14273715369;9613;9687.70936871895;20492.18031278762;20518.897405513177;20521.973901304875;20525.58948821045;20612.69080963787;7451.254874512505;7605.049368828896;7759.961200194076;7918.208179182151;8038.075621303625;3561.3767590671528;4067.3288907330552;4083.4283200351974;4090.6727461802984;4185.079282557833;3507.2810073934784;3583.354996100727;3603.549556305462;3608.476770868262;3625.5649322081517;539.6519245086897;645.5029627187133;657.6021506988203;664.1432552007952;682.3108660253081;477.4461624515545;479.8920242945309;510.12004292594975;515.3249243491039;594.4887396838715;7498.437617178624;7992.720254791055;8096.676132954616;8299.460535065202;8466.703665371682;493.9678920870132;517.0357283666011;521.9925543700695;526.1607735661613;536.9597280204;3852.8073596320073;3888.96111038886;3909.7654140751647;3946;4117.3618089196225;93857.06142938483;97238.51380743051;97357;99666;100570.4971475138;81404.59297703474;81913;82286.58856705678;83516.58241708753;84445.15554844496;74755;75796;76129.9999999989;76411.23588764042;76548.23451765522;7655.46411751172;7775.455718099679;7801.687932482637;7833.334166595675;7911.604419778873;46973.530264696914;47456;48228;48482;48585.05659773711;61919.761604766754;63556.04665929922;65652.67173664292;66568.33431665621;67294.32705672881;10037.749056273651;10088.596456141671;10158.28891977584;10244.282900197213;10450.059494645518;11190.94404527972;11200.159988000769;11240.887591123985;11322.660320190407;11337.093032557541;10558.894411055791;10669.786604267716;10708.62519811834;10742.979416955377;10903.83644245321;10156.136728378171;10182.694519164437;10463.581456741646;10505;10549.261551691308;10337.74155646115;10367.170626350025;10696.465176741287;10721.785564288826;10739.194560408157;8878.067802880863;9056.864147037932;9347.906520934703;9398.060193980547;9586.904130958601;8804.779880503038;8975.955120224357;9062.093790620886;9080.954595226982;9119 21:19:18 INFO - PROCESS | 1676 | __end_tp_report 21:19:18 INFO - PROCESS | 1676 | __start_cc_report 21:19:18 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1155 21:19:18 INFO - PROCESS | 1676 | __end_cc_report 21:19:18 INFO - PROCESS | 1676 | __startTimestamp1463631558862__endTimestamp 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | ------- Summary: start ------- 21:19:18 INFO - PROCESS | 1676 | Number of tests: 6 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5640.91 Median:5865.56 stddev:731.56 (12.5%) stddev-sans-first:711.16 21:19:18 INFO - PROCESS | 1676 | Values: 3338.5 3408.7 3423.9 3458.4 3471.9 5567.5 5582.2 5597.9 5644.0 5711.3 5598.9 5690.0 5692.8 5692.8 5693.4 5119.3 5137.9 5146.0 5194.9 5267.3 5151.0 5178.0 5215.8 5236.3 5245.6 5207.6 5263.6 5274.0 5274.9 5327.3 988.9 5073.8 5144.7 5193.0 5210.3 5092.6 5150.2 5175.4 5180.7 5183.7 5107.7 5379.9 5515.1 5546.6 5687.0 6030.2 6053.8 6099.8 6109.1 6114.6 5413.7 6218.2 6244.7 6249.4 6253.5 5752.1 5758.5 5784.8 5792.3 5797.0 6239.8 6259.9 6305.2 6312.4 6343.6 6078.1 6096.6 6101.9 6102.1 6106.2 6170.5 6226.0 6226.9 6229.8 6234.8 5990.1 6058.8 6111.9 6130.5 6190.4 6183.5 6210.8 6221.6 6221.8 6228.4 4899.9 4951.5 4956.2 4970.4 4984.5 4362.9 4387.5 4429.9 4436.7 4473.0 5593.3 5602.7 5625.5 5627.5 5637.1 6196.3 6227.6 6238.6 6284.4 6298.2 5479.7 5496.5 5518.8 5575.9 5628.2 5311.8 5319.1 5327.6 5353.4 5375.5 6289.0 6303.6 6306.3 6314.7 6321.9 6096.9 6101.3 6124.8 6156.4 6200.8 6300.7 6332.9 6334.2 6336.2 6355.6 6090.4 6134.6 6139.6 6143.4 6172.3 4596.9 6100.7 6112.5 6128.4 6164.8 5966.6 5967.6 5971.9 5972.5 5982.8 5946.6 5982.0 5982.3 5985.1 5992.6 4268.6 4920.8 5731.4 5781.0 5854.9 6017.7 6051.3 6054.2 6065.3 6125.4 5723.3 5865.6 5869.0 5874.2 5875.0 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:69681.18 Median:38459.23 stddev:146596.91 (381.2%) stddev-sans-first:146916.10 21:19:18 INFO - PROCESS | 1676 | Values: 486.2 495.1 509.9 517.8 536.5 25129.6 42999.8 43924.0 43925.1 44149.3 45434.8 45625.3 45723.5 45753.6 46026.8 8647.0 9659.1 9876.3 9882.0 9894.6 8696.0 8806.7 8821.0 8833.0 11680.8 11660.9 11905.7 11919.9 11930.3 11960.2 10427.9 11448.0 11727.8 11851.7 11874.8 8634.4 11472.5 11671.8 11799.9 11805.1 41505.6 41832.8 41984.4 42027.2 42099.4 3269.8 3326.2 3374.6 3476.5 3710.0 23644.8 28797.9 29419.0 29589.7 31114.9 2702.3 2713.2 2737.5 2742.4 2750.9 515089.0 516576.0 516994.0 517674.4 518820.0 63804.7 64220.7 64231.0 64289.7 64324.0 67801.0 67886.0 67904.7 67957.0 68083.7 16503.6 17194.4 17243.9 17251.7 17285.2 12132.0 12286.6 12359.3 12569.4 12600.9 17259.6 17399.4 17601.5 17651.7 17899.1 6440.9 6456.3 6465.7 6505.4 6522.8 892.0 993.8 1001.8 1027.6 1034.4 25118.0 30206.7 30376.9 30585.8 30652.1 1030.2 1067.3 1069.4 1144.9 1146.8 13574.5 15604.5 15629.0 15675.3 16031.0 514011.0 602045.0 613638.0 619961.0 621126.0 62358.4 62583.1 62744.0 62789.4 62790.0 1274.6 1395.6 1402.7 1415.7 1453.0 31043.7 31065.7 31160.0 31782.7 31895.2 53706.2 54409.0 54497.2 54591.7 54667.5 533166.0 534956.0 535482.0 536201.0 536346.0 34102.8 40824.2 42591.8 43972.3 44677.6 46662.5 48281.0 48323.8 48519.8 49314.0 37103.4 38488.4 38496.0 39627.0 40188.8 37748.0 38280.4 38283.6 38922.2 39195.6 38159.2 38430.0 38845.4 39295.0 39358.0 32123.5 33601.5 34177.0 34189.1 34206.3 21544.6 24852.3 24992.0 25516.4 25669.9 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:35746.83 Median:14312.58 stddev:103667.02 (724.3%) stddev-sans-first:103923.82 21:19:18 INFO - PROCESS | 1676 | Values: 384.7 388.2 395.2 398.5 418.5 20047.0 20262.3 21264.4 21316.5 21350.7 20505.9 20944.5 22272.3 22557.2 22607.0 6075.5 6370.6 6400.2 6420.9 6421.5 6206.7 6266.7 6267.0 6289.7 6294.3 6020.2 6239.8 6247.5 6256.2 6283.6 6093.8 6199.9 6233.8 6243.1 6258.8 5170.0 5233.9 5283.7 5301.6 5322.5 24448.8 25543.0 26023.5 26888.6 26909.9 2161.4 2269.1 2395.8 2505.1 2601.7 10304.2 10325.5 10434.6 10666.1 10724.3 1748.3 1820.1 1829.1 1837.9 1855.6 122191.0 134293.0 135192.0 135624.3 136182.0 11928.6 11933.9 11939.8 11975.7 11980.2 15537.8 15567.8 15585.6 15603.2 15712.6 8901.7 8919.9 8950.5 8979.6 9057.5 8772.4 8789.0 8812.2 8944.6 8962.4 4107.4 4683.3 4728.1 4746.8 4787.2 1797.5 1961.3 1968.0 1968.2 2002.9 715.2 806.7 866.9 964.3 1001.7 16642.8 20652.2 21085.8 21206.7 21250.1 923.8 941.4 961.2 972.5 980.8 11137.4 11552.5 11579.5 11623.0 11630.8 602128.0 618972.0 619550.0 630176.0 638320.0 67661.0 78637.0 113519.4 138664.0 149041.8 977.4 1017.5 1039.0 1040.0 1040.5 18842.6 19227.4 19372.0 19389.4 19451.7 36696.9 46002.8 51367.0 53169.0 53396.7 79556.6 81550.0 83002.3 83374.0 83537.6 8091.6 8279.3 8421.2 8435.0 8554.7 9257.0 12066.7 12683.1 13165.6 13172.6 10972.6 10987.7 11154.9 11348.7 11430.9 9233.7 9554.8 10641.8 11116.4 11282.3 11020.8 11406.5 11442.9 11613.1 11721.7 8160.5 8372.9 8394.3 8423.9 8656.6 9922.8 10912.7 11422.8 11444.8 11552.5 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:6019.53 Median:2642.98 stddev:3417.14 (129.3%) stddev-sans-first:3408.78 21:19:18 INFO - PROCESS | 1676 | Values: 1360.1 1623.7 1629.0 1879.2 1990.4 8225.7 8883.7 9075.5 9103.3 9234.3 8736.5 8990.2 9017.1 9024.9 9035.5 2214.6 2296.2 2299.6 2492.0 2527.9 2485.2 2573.9 2576.8 2583.1 2584.7 2468.5 2530.6 2534.6 2547.8 2554.4 2199.3 2461.4 2517.2 2551.5 2558.4 2533.6 2564.0 2567.9 2569.2 2573.8 9183.8 9219.6 9223.6 9229.3 9278.8 5488.7 5673.6 5675.8 5684.9 5692.4 3554.2 3597.9 3744.3 3783.6 3803.4 1066.9 1067.6 1069.0 1073.2 1080.6 12529.2 12570.7 12574.8 12574.9 12590.4 4962.6 5078.9 5136.9 5156.1 5156.7 10525.5 10634.0 10634.1 10641.0 10650.0 5717.3 5718.8 5722.3 5722.5 5767.7 4261.8 4506.4 5068.2 5188.1 5226.2 4175.5 4210.2 4244.2 4278.2 4295.9 1349.3 1372.8 1373.3 1384.7 1386.7 886.4 921.8 935.5 950.1 969.5 6062.0 6105.6 6111.8 6167.5 6284.1 463.5 469.6 474.8 476.4 486.7 3920.7 4052.4 4196.3 4201.9 4211.6 11096.6 11116.6 11568.5 11593.5 11596.0 11315.7 11510.0 11536.5 11547.5 11566.0 8151.5 8560.9 8575.4 8615.2 8693.6 4799.2 6080.4 6125.8 6266.5 6322.1 10347.6 10638.6 10675.9 10694.9 10701.6 9731.6 10043.9 10105.4 10598.7 10618.6 7741.5 8127.8 8415.9 8657.9 8662.4 8782.0 8926.5 8929.9 8937.6 8945.8 7738.4 7886.2 7909.0 8061.3 8083.6 7600.8 7729.6 7755.1 7851.5 7907.0 7265.8 7392.5 7405.9 7415.2 7428.0 4892.9 5105.0 5105.1 5172.7 5210.7 5207.7 5217.6 5261.9 5402.1 5488.0 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:4545.16 Median:5140.25 stddev:1398.12 (27.2%) stddev-sans-first:1364.96 21:19:18 INFO - PROCESS | 1676 | Values: 283.2 310.4 314.8 325.1 332.0 4363.0 5275.9 5425.9 5451.9 5467.8 4279.3 5319.5 5347.2 5368.6 5404.1 4144.9 4145.8 4153.7 4163.4 4200.2 4156.9 4170.3 4175.1 4178.9 4181.7 3158.6 3489.2 3817.3 3966.4 3970.5 3474.4 3507.4 3805.4 4083.6 4093.6 3157.3 3574.7 3582.2 3585.1 3597.9 4768.4 4802.5 5130.4 5218.3 5242.7 3803.2 3891.6 4225.7 4562.0 4681.8 4681.8 5237.4 5641.0 5751.2 5753.7 3634.6 3878.8 3891.4 3900.7 3922.4 5739.4 5909.4 6018.6 6032.7 6067.3 3850.5 3928.4 3934.6 3934.9 3937.5 3812.6 4000.4 4002.3 4037.0 4079.6 4063.0 4115.0 4191.4 4590.9 4694.5 5210.9 5278.9 5708.2 5815.7 5840.6 1333.2 1363.1 1372.3 1380.0 1385.4 827.7 845.6 849.7 866.7 925.3 4695.0 4736.0 4874.8 5209.1 5266.6 5316.3 5447.1 5741.8 5787.3 5789.4 4367.5 4420.8 4897.7 5222.1 5316.6 2740.9 2971.7 3084.8 3088.5 3105.3 5625.0 5683.8 5896.7 6049.3 6109.7 5313.0 5642.5 5660.6 5973.3 6032.8 5057.9 5397.7 5758.2 6043.9 6099.8 5492.6 5541.5 5832.8 5841.6 5850.0 4598.5 5511.4 5764.3 5765.7 5776.0 5140.8 5923.6 5958.6 6035.0 6047.1 3889.6 3892.9 3913.0 3916.5 3923.7 5135.4 5155.6 5318.6 5864.5 5883.8 5316.1 5407.9 5665.8 5697.9 5727.3 4845.6 5112.8 5640.5 5735.7 5742.3 5081.0 5315.9 5472.2 5567.4 5579.6 4825.2 5114.8 5139.7 5181.3 5492.4 5369.1 5454.1 5619.4 5621.7 5641.3 21:19:18 INFO - PROCESS | 1676 | 21:19:18 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:18348.20 Median:567.07 stddev:26345.94 (4646.0%) stddev-sans-first:26386.26 21:19:18 INFO - PROCESS | 1676 | Values: 630.8 700.9 730.5 734.6 742.6 14658.4 14951.4 15442.5 15451.9 15497.2 14061.3 14209.3 14238.0 14255.3 14324.1 717.1 723.0 894.2 908.1 931.2 907.0 914.7 916.4 917.8 921.5 964.5 965.6 965.7 966.7 969.2 738.9 933.3 942.1 946.3 959.0 721.1 722.1 723.1 724.1 724.5 14880.3 14887.0 14888.3 14936.6 14953.5 11847.9 12141.8 12253.5 12339.1 12541.4 10767.7 10965.7 11102.7 11572.2 11595.7 3501.0 3971.7 4082.3 4173.3 4187.4 75095.6 75772.0 76031.6 77438.6 78161.0 7763.4 9387.5 9525.1 9613.0 9687.7 20492.2 20518.9 20522.0 20525.6 20612.7 7451.3 7605.0 7760.0 7918.2 8038.1 3561.4 4067.3 4083.4 4090.7 4185.1 3507.3 3583.4 3603.5 3608.5 3625.6 539.7 645.5 657.6 664.1 682.3 477.4 479.9 510.1 515.3 594.5 7498.4 7992.7 8096.7 8299.5 8466.7 494.0 517.0 522.0 526.2 537.0 3852.8 3889.0 3909.8 3946.0 4117.4 93857.1 97238.5 97357.0 99666.0 100570.5 81404.6 81913.0 82286.6 83516.6 84445.2 74755.0 75796.0 76130.0 76411.2 76548.2 7655.5 7775.5 7801.7 7833.3 7911.6 46973.5 47456.0 48228.0 48482.0 48585.1 61919.8 63556.0 65652.7 66568.3 67294.3 10037.7 10088.6 10158.3 10244.3 10450.1 11190.9 11200.2 11240.9 11322.7 11337.1 10558.9 10669.8 10708.6 10743.0 10903.8 10156.1 10182.7 10463.6 10505.0 10549.3 10337.7 10367.2 10696.5 10721.8 10739.2 8878.1 9056.9 9347.9 9398.1 9586.9 8804.8 8976.0 9062.1 9081.0 9119.0 21:19:18 INFO - PROCESS | 1676 | -------- Summary: end -------- 21:19:18 INFO - PROCESS | 1676 | 21:19:19 INFO - TEST-INFO | 1676: exit 0 21:19:19 INFO - TEST-OK | dromaeo_css | took 1115823ms 21:19:19 INFO - TEST-START | kraken 21:19:19 INFO - operating with platform_type : mac_ 21:19:19 INFO - Initialising browser for kraken test... 21:19:19 INFO - TEST-INFO | started process 1797 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpUSQcGK/profile http://localhost:49278/getInfo.html) 21:19:21 INFO - PROCESS | 1797 | __metrics Screen width/height:1600/1200 21:19:21 INFO - PROCESS | 1797 | colorDepth:24 21:19:21 INFO - PROCESS | 1797 | Browser inner width/height: 1024/689 21:19:21 INFO - PROCESS | 1797 | __metrics 21:19:21 INFO - PROCESS | 1797 | 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" 21:19:21 ERROR - PROCESS | 1797 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:19:22 INFO - TEST-INFO | 1797: exit 0 21:19:22 INFO - Browser initialized. 21:19:22 INFO - Running cycle 1/1 for kraken test... 21:19:22 INFO - TEST-INFO | started process 1799 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpUSQcGK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:19:34 INFO - PROCESS | 1799 | RSS: Main: 239087616 21:19:34 INFO - PROCESS | 1799 | 21:19:37 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 21:19:38 INFO - PROCESS | 1799 | RSS: Main: 287649792 21:19:38 INFO - PROCESS | 1799 | 21:19:41 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 21:19:41 INFO - PROCESS | 1799 | RSS: Main: 296153088 21:19:41 INFO - PROCESS | 1799 | 21:19:45 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 21:19:46 INFO - PROCESS | 1799 | RSS: Main: 388358144 21:19:46 INFO - PROCESS | 1799 | 21:19:48 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 21:19:48 INFO - PROCESS | 1799 | RSS: Main: 286384128 21:19:48 INFO - PROCESS | 1799 | 21:19:51 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:19:51 INFO - PROCESS | 1799 | RSS: Main: 290353152 21:19:51 INFO - PROCESS | 1799 | 21:19:54 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 21:19:55 INFO - PROCESS | 1799 | RSS: Main: 328749056 21:19:55 INFO - PROCESS | 1799 | 21:19:58 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 21:19:59 INFO - PROCESS | 1799 | RSS: Main: 328536064 21:19:59 INFO - PROCESS | 1799 | 21:20:02 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 21:20:02 INFO - PROCESS | 1799 | RSS: Main: 326438912 21:20:02 INFO - PROCESS | 1799 | 21:20:04 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:20:05 INFO - PROCESS | 1799 | RSS: Main: 316358656 21:20:05 INFO - PROCESS | 1799 | 21:20:07 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:20:07 INFO - PROCESS | 1799 | RSS: Main: 323592192 21:20:07 INFO - PROCESS | 1799 | 21:20:09 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:20:10 INFO - PROCESS | 1799 | RSS: Main: 324784128 21:20:10 INFO - PROCESS | 1799 | 21:20:13 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:20:14 INFO - PROCESS | 1799 | RSS: Main: 329515008 21:20:14 INFO - PROCESS | 1799 | 21:20:17 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:20:17 INFO - PROCESS | 1799 | RSS: Main: 327335936 21:20:17 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 21:20:19 INFO - PROCESS | 1799 | RSS: Main: 373522432 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | __start_tp_report 21:20:19 INFO - PROCESS | 1799 | _x_x_mozilla_page_load 21:20:19 INFO - PROCESS | 1799 | _x_x_mozilla_page_load_details 21:20:19 INFO - PROCESS | 1799 | |i|pagename|runs| 21:20:19 INFO - PROCESS | 1799 | |0;ai-astar;108;99;112;110;112;111;112;112;111;112 21:20:19 INFO - PROCESS | 1799 | |1;audio-beat-detection;161;146;165;196;125;163;155;125;206;142 21:20:19 INFO - PROCESS | 1799 | |2;audio-dft;183;185;187;259;205;185;186;183;183;180 21:20:19 INFO - PROCESS | 1799 | |3;audio-fft;80;73;56;125;57;63;64;70;149;56 21:20:19 INFO - PROCESS | 1799 | |4;audio-oscillator;95;93;132;79;83;76;103;80;80;101 21:20:19 INFO - PROCESS | 1799 | |5;imaging-gaussian-blur;90;87;94;130;136;86;90;88;90;86 21:20:19 INFO - PROCESS | 1799 | |6;imaging-darkroom;95;94;95;96;94;94;95;94;100;147 21:20:19 INFO - PROCESS | 1799 | |7;imaging-desaturate;82;84;84;86;82;83;83;83;83;86 21:20:19 INFO - PROCESS | 1799 | |8;json-parse-financial;57;55;58;57;55;57;57;119;58;55 21:20:19 INFO - PROCESS | 1799 | |9;json-stringify-tinderbox;62;66;68;73;70;66;65;65;67;66 21:20:19 INFO - PROCESS | 1799 | |10;stanford-crypto-aes;85;85;85;84;83;83;81;84;83;86 21:20:19 INFO - PROCESS | 1799 | |11;stanford-crypto-ccm;136;135;134;132;136;134;131;134;216;138 21:20:19 INFO - PROCESS | 1799 | |12;stanford-crypto-pbkdf2;160;163;161;160;217;225;162;161;163;160 21:20:19 INFO - PROCESS | 1799 | |13;stanford-crypto-sha256-iterative;73;89;84;88;85;63;64;64;65;65 21:20:19 INFO - PROCESS | 1799 | __end_tp_report 21:20:19 INFO - PROCESS | 1799 | __start_cc_report 21:20:19 INFO - PROCESS | 1799 | _x_x_mozilla_cycle_collect,2227 21:20:19 INFO - PROCESS | 1799 | __end_cc_report 21:20:19 INFO - PROCESS | 1799 | __startTimestamp1463631619923__endTimestamp 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | ------- Summary: start ------- 21:20:19 INFO - PROCESS | 1799 | Number of tests: 14 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#0] ai-astar Cycles:10 Average:109.90 Median:112.00 stddev:4.04 (3.6%) stddev-sans-first:4.23 21:20:19 INFO - PROCESS | 1799 | Values: 108.0 99.0 112.0 110.0 112.0 111.0 112.0 112.0 111.0 112.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#1] audio-beat-detection Cycles:10 Average:158.40 Median:162.00 stddev:26.70 (16.5%) stddev-sans-first:28.30 21:20:19 INFO - PROCESS | 1799 | Values: 161.0 146.0 165.0 196.0 125.0 163.0 155.0 125.0 206.0 142.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#2] audio-dft Cycles:10 Average:193.60 Median:185.50 stddev:23.99 (12.9%) stddev-sans-first:25.13 21:20:19 INFO - PROCESS | 1799 | Values: 183.0 185.0 187.0 259.0 205.0 185.0 186.0 183.0 183.0 180.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#3] audio-fft Cycles:10 Average:79.30 Median:63.50 stddev:31.90 (50.2%) stddev-sans-first:33.83 21:20:19 INFO - PROCESS | 1799 | Values: 80.0 73.0 56.0 125.0 57.0 63.0 64.0 70.0 149.0 56.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:80.00 stddev:17.02 (21.3%) stddev-sans-first:18.02 21:20:19 INFO - PROCESS | 1799 | Values: 95.0 93.0 132.0 79.0 83.0 76.0 103.0 80.0 80.0 101.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#5] imaging-gaussian-blur Cycles:10 Average:97.70 Median:89.00 stddev:18.81 (21.1%) stddev-sans-first:19.74 21:20:19 INFO - PROCESS | 1799 | Values: 90.0 87.0 94.0 130.0 136.0 86.0 90.0 88.0 90.0 86.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:94.50 stddev:16.47 (17.4%) stddev-sans-first:17.36 21:20:19 INFO - PROCESS | 1799 | Values: 95.0 94.0 95.0 96.0 94.0 94.0 95.0 94.0 100.0 147.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#7] imaging-desaturate Cycles:10 Average:83.60 Median:83.50 stddev:1.43 (1.7%) stddev-sans-first:1.39 21:20:19 INFO - PROCESS | 1799 | Values: 82.0 84.0 84.0 86.0 82.0 83.0 83.0 83.0 83.0 86.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:57.00 stddev:19.78 (34.7%) stddev-sans-first:20.87 21:20:19 INFO - PROCESS | 1799 | Values: 57.0 55.0 58.0 57.0 55.0 57.0 57.0 119.0 58.0 55.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:66.50 stddev:3.01 (4.5%) stddev-sans-first:2.65 21:20:19 INFO - PROCESS | 1799 | Values: 62.0 66.0 68.0 73.0 70.0 66.0 65.0 65.0 67.0 66.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.50 stddev:1.45 (1.7%) stddev-sans-first:1.48 21:20:19 INFO - PROCESS | 1799 | Values: 85.0 85.0 85.0 84.0 83.0 83.0 81.0 84.0 83.0 86.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:135.50 stddev:25.87 (19.1%) stddev-sans-first:27.33 21:20:19 INFO - PROCESS | 1799 | Values: 136.0 135.0 134.0 132.0 136.0 134.0 131.0 134.0 216.0 138.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:162.50 stddev:25.29 (15.6%) stddev-sans-first:26.37 21:20:19 INFO - PROCESS | 1799 | Values: 160.0 163.0 161.0 160.0 217.0 225.0 162.0 161.0 163.0 160.0 21:20:19 INFO - PROCESS | 1799 | 21:20:19 INFO - PROCESS | 1799 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:78.50 stddev:11.19 (14.2%) stddev-sans-first:11.86 21:20:19 INFO - PROCESS | 1799 | Values: 73.0 89.0 84.0 88.0 85.0 63.0 64.0 64.0 65.0 65.0 21:20:19 INFO - PROCESS | 1799 | -------- Summary: end -------- 21:20:19 INFO - PROCESS | 1799 | 21:20:20 INFO - TEST-INFO | 1799: exit 0 21:20:20 INFO - TEST-OK | kraken | took 61366ms 21:20:20 INFO - SUITE-END | took 1177s 21:20:21 INFO - Completed test suite (00:19:37) 21:20:21 INFO - javascript benchmark 21:20:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.465845464726, 3408.6932176104146, 3423.931521369574, 3458.4293591557393, 3471.878484253051, 5567.47109024642, 5582.19058236556, 5597.888042239153, 5643.971780141099, 5711.3431955325095, 5598.888022239563, 5690.0, 5692.772289108441, 5692.829215123553, 5693.373728889819, 5119.308893299403, 5137.871553211181, 5145.999999999981, 5194.870128246786, 5267.288915996349, 5151.047056294578, 5177.974110129444, 5215.765290561933, 5236.31927849379, 5245.580353571724, 5207.635465517396, 5263.605229607776, 5274.0, 5274.92087618686, 5327.280817089682, 988.9332713059573, 5073.797048118071, 5144.717040562768, 5193.0, 5210.270562121306, 5092.567131793802, 5150.201718733602, 5175.353080864892, 5180.715060671661, 5183.714895680736, 5107.693538387709, 5379.865503362447, 5515.117581186991, 5546.639468434539, 5687.0, 6030.185924900126, 6053.783739359561, 6099.847503812396, 6109.105395513301, 6114.633122012693, 5413.729313534308, 6218.19163508741, 6244.718987645567, 6249.437550620466, 6253.499720022387, 5752.050911599581, 5758.510526605201, 5784.768609255667, 5792.275965504312, 5796.971015144897, 6239.844003899939, 6259.937400625936, 6305.243370795442, 6312.368763123743, 6343.619382837044, 6078.05534108743, 6096.600930733159, 6101.932161871655, 6102.050500761722, 6106.236720409949, 6170.475509581646, 6226.003839385677, 6226.875462490725, 6229.844253893689, 6234.750609975641, 5990.101484777319, 6058.818235452944, 6111.908321375183, 6130.540209484307, 6190.442860142609, 6183.474404675653, 6210.7515699371515, 6221.595596286227, 6221.844453888599, 6228.408301211378, 4899.926501102486, 4951.529604687549, 4956.157453232959, 4970.403551573834, 4984.476629953875, 4362.934555981663, 4387.451568553931, 4429.911401771947, 4436.689431739747, 4472.9552704472535, 5593.272874526367, 5602.719864006784, 5625.46558076982, 5627.52166065889, 5637.069883469182, 6196.256449226122, 6227.626342419469, 6238.594491358047, 6284.4344009039405, 6298.244210694655, 5479.726013699299, 5496.5053145217125, 5518.779248830082, 5575.888482230333, 5628.183913332544, 5311.760970756325, 5319.069162896478, 5327.573794096463, 5353.437889021675, 5375.5162035417, 6288.968555157195, 6303.621782693053, 6306.3378345274, 6314.652694101868, 6321.936780632135, 6096.919795645128, 6101.29835068955, 6124.785632502841, 6156.353582873734, 6200.782972595937, 6300.684965751602, 6332.936670633235, 6334.208223972004, 6336.20797400325, 6355.586886852339, 6090.3605121461605, 6134.5705800595315, 6139.6009259398, 6143.447089761943, 6172.259328880384, 4596.908061838812, 6100.725467354068, 6112.480439162543, 6128.356522565066, 6164.7842325518395, 5966.552508561788, 5967.552433567586, 5971.940280597312, 5972.492338151305, 5982.790602328898, 5946.583739138392, 5982.042873140276, 5982.252218472691, 5985.132155837467, 5992.64044157352, 4268.59448352406, 4920.803167873245, 5731.398203188772, 5780.959427303126, 5854.853628659318, 6017.699115044318, 6051.262668961075, 6054.152418661479, 6065.332813390612, 6125.356837532173, 5723.313202415737, 5865.5600829937075, 5868.970655146697, 5874.206982057539, 5875.0], "value": 5597.794550581284, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.2414633172254, 495.1137463939548, 509.9490050994901, 517.7540668182617, 536.4742552298748, 25129.623055654156, 42999.78500107499, 43924.0, 43925.12149757011, 44149.33775993354, 45434.77282613591, 45625.31562026572, 45723.54276457235, 45753.62739117832, 46026.76986615071, 8646.956765216197, 9659.130678238975, 9876.308658393915, 9881.95059024704, 9894.604215831358, 8695.95652021739, 8806.691765788199, 8821.0, 8833.0, 11680.824787628193, 11660.883391166108, 11905.70235744109, 11919.94040029803, 11930.284182949008, 11960.222585531912, 10427.895721042769, 11447.969682728595, 11727.824082638766, 11851.703707407298, 11874.762504749942, 8634.352423568258, 11472.541098356056, 11671.824922626167, 11799.882001179963, 11805.114616403804, 41505.584944150476, 41832.790836045926, 41984.370234446505, 42027.159456811, 42099.368509472384, 3269.836508174582, 3326.185084654275, 3374.6119196292493, 3476.495908093312, 3709.981450092759, 23644.763552364428, 28797.85601072002, 29419.0, 29589.70410295891, 31114.910978115884, 2702.27038699552, 2713.186044186736, 2737.4798788230173, 2742.3829638331417, 2750.876210570509, 515089.0, 516576.0, 516994.0, 517674.4116279432, 518820.0, 63804.68097659482, 64220.67889660615, 64231.0, 64289.67855160694, 64324.0, 67801.0, 67886.0, 67904.6604766973, 67957.0, 68083.65958170178, 16503.587410314838, 17194.398196063077, 17243.913780431267, 17251.74122388165, 17285.222165002604, 12132.0, 12286.631401057983, 12359.32023738703, 12569.371531423576, 12600.936995315147, 17259.568510787332, 17399.391021314193, 17601.471955841345, 17651.73522397165, 17899.10504474771, 6440.903386449207, 6456.289808121103, 6465.741370345134, 6505.381988711065, 6522.771228242051, 891.9821603567892, 993.7515621094726, 1001.7846163074828, 1027.5786927359748, 1034.4362322534087, 25118.0, 30206.697933020387, 30376.93680721164, 30585.84707076495, 30652.08043758691, 1030.1707125763683, 1067.3275836223129, 1069.395791377869, 1144.856892888389, 1146.8451759012594, 13574.524891628746, 15604.531864043869, 15629.0, 15675.294611742498, 16031.0, 514011.0, 602044.9897750571, 613638.0, 619961.0, 621126.0, 62358.37641623616, 62583.06125408031, 62744.0, 62789.37210627927, 62790.0, 1274.6239859241546, 1395.5883014510744, 1402.6633607933818, 1415.7380884536394, 1453.026472263586, 31043.689563104985, 31065.689343106278, 31160.0, 31782.68217317797, 31895.202619934687, 53706.19440708471, 54409.0, 54497.18254226111, 54591.72704136454, 54667.45332546624, 533166.0, 534956.0, 535482.0, 536201.0, 536346.0, 34102.82948585241, 40824.1835163301, 42591.7870410646, 43972.340414893166, 44677.55322446734, 46662.53337466582, 48281.03437931152, 48323.75838120928, 48519.757401212766, 49314.0, 37103.44344834776, 38488.422673660476, 38496.0, 39627.0, 40188.79905600454, 37748.0, 38280.42579361368, 38283.61716382801, 38922.2215555693, 39195.608043919194, 38159.236815262986, 38430.03924901788, 38845.417318739674, 39295.0, 39358.0, 32123.51814722828, 33601.495977560844, 34177.0, 34189.14527136742, 34206.315873682885, 21544.569108618052, 24852.25443236706, 24992.0, 25516.362090947874, 25669.871650642373], "value": 20503.11765482965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.6711062041954, 388.21192978254135, 395.15239810606266, 398.51978366068874, 418.53751604477054, 20046.99765011749, 20262.290819821308, 21264.362069137933, 21316.46708832278, 21350.679739803876, 20505.872177030258, 20944.476388090305, 22272.331830045125, 22557.210497632586, 22607.0, 6075.483583895374, 6370.649614271213, 6400.199975003125, 6420.871582568346, 6421.486281097501, 6206.689665516728, 6266.655333956631, 6266.968665156668, 6289.716962736665, 6294.2761582417925, 6020.157177995085, 6239.750409983595, 6247.500199983991, 6256.155419018442, 6283.622982621035, 6093.78671746489, 6199.907001394983, 6233.812985610393, 6243.125962365295, 6258.780942667007, 5169.991851588941, 5233.921491177635, 5283.682979021271, 5301.602379821529, 5322.494363035506, 24448.755512444826, 25543.0, 26023.479530409477, 26888.59667104995, 26909.865450672816, 2161.438026113206, 2269.1150451323997, 2395.7843794058617, 2505.08564374004, 2601.687797464316, 10304.227182961307, 10325.48372581368, 10434.634787782392, 10666.146708263397, 10724.302920310154, 1748.2919417635878, 1820.0990509697651, 1829.1128802530761, 1837.8529717622557, 1855.5824939388665, 122191.0, 134293.0, 135191.999999999, 135624.32187839097, 136182.0, 11928.642140735792, 11933.88066119345, 11939.761384708303, 11975.700607484709, 11980.161388702707, 15537.766933496006, 15567.766483502757, 15585.610359741097, 15603.219839008005, 15712.60718482047, 8901.688440904538, 8919.866202006975, 8950.462972221558, 8979.595918183697, 9057.456552606733, 8772.38593298463, 8789.033206347296, 8812.206901378779, 8944.642214311485, 8962.417442866194, 4107.363358679409, 4683.274092515666, 4728.148933192058, 4746.786394612251, 4787.234042553245, 1797.4901083090006, 1961.3331467301186, 1967.9606407871763, 1968.2149758092426, 2002.859799813999, 715.1668306423063, 806.681360862456, 866.9089745576749, 964.320154291237, 1001.6744558018528, 16642.75035874463, 20652.17391304331, 21085.78914210869, 21206.681899771516, 21250.149994000378, 923.7921467669721, 941.3970371921642, 961.2022021721954, 972.4700038478904, 980.764616492051, 11137.387443690675, 11552.537898484135, 11579.478923448465, 11623.0, 11630.825537616773, 602128.0, 618972.0, 619550.0, 630176.0, 638320.0, 67661.0, 78637.0, 113519.43240283911, 138664.0, 149041.76437353448, 977.4428575711918, 1017.4963393120305, 1039.0440794469107, 1040.0119886108075, 1040.484959944817, 18842.623147537248, 19227.423177304703, 19372.0, 19389.418317450498, 19451.735637183538, 36696.94500407403, 46002.769986149855, 51367.0, 53169.0, 53396.73301633467, 79556.60221698854, 81550.0, 83002.3399531994, 83374.0, 83537.58231209048, 8091.595420228848, 8279.291287873086, 8421.15788421153, 8434.957825210835, 8554.657813687632, 9256.95371523138, 12066.698332541406, 12683.11218214716, 13165.60503184906, 13172.60482185536, 10972.615958441416, 10987.725306867073, 11154.888451115385, 11348.665318251187, 11430.885691143314, 9233.67682131149, 9554.761130971781, 10641.840372394265, 11116.444177790916, 11282.323060616389, 11020.779584408107, 11406.54373825071, 11442.885571144516, 11613.129015323715, 11721.706957326134, 8160.5103693778565, 8372.874406884024, 8394.286485648545, 8423.8736418955, 8656.610452529525, 9922.751931201778, 10912.672619821418, 11422.82865757031, 11444.82832757526, 11552.537898483966], "value": 9738.795892388098, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.129517109051, 1623.7239669256226, 1628.9755653665195, 1879.1731638079243, 1990.3531352310495, 8225.670973161074, 8883.689070882521, 9075.455472671645, 9103.271738260939, 9234.261259099272, 8736.519491427985, 8990.190882820545, 9017.098290170996, 9024.909750902489, 9035.457872527653, 2214.6235140026156, 2296.242240060776, 2299.620562607168, 2491.9750802492017, 2527.8736063196857, 2485.2047344849657, 2573.8841752121107, 2576.8325058871205, 2583.0959164292535, 2584.741525847419, 2468.5309791139716, 2530.6204069389646, 2534.581794003987, 2547.7579629935126, 2554.3869471326934, 2199.3182113544854, 2461.384653836541, 2517.1944977607172, 2551.5407226699185, 2558.4499332643477, 2533.6199570064455, 2563.9871800640876, 2567.87160641969, 2569.203546900467, 2573.7812285955715, 9183.770405739842, 9219.631214751402, 9223.584938677775, 9229.307801914883, 9278.768030799216, 5488.69812160331, 5673.574481913832, 5675.801346952878, 5684.943150568482, 5692.37383887772, 3554.182538016245, 3597.94603080954, 3744.326021316162, 3783.5838057813617, 3803.410471376941, 1066.8773091094547, 1067.5783065689009, 1069.03786592067, 1073.2111897755144, 1080.5947769586405, 12529.248245105322, 12570.68573285666, 12574.81137782934, 12574.937125314314, 12590.37048147598, 4962.553370196628, 5078.873028174251, 5136.8715782105, 5156.123459011977, 5156.716380599103, 10525.526351314209, 10633.989770971746, 10634.149268058536, 10641.0, 10649.988251116132, 5717.342505611825, 5718.79984200551, 5722.2561067061015, 5722.484976352148, 5767.711614419262, 4261.829526818892, 4506.355180359145, 5068.189089745623, 5188.118019936544, 5226.216067589892, 4175.478065241845, 4210.200061988213, 4244.193603215441, 4278.165757677223, 4295.935560966588, 1349.3185941099682, 1372.8078069070339, 1373.3108276819569, 1384.6607581142684, 1386.6757246829254, 886.3795343259615, 921.7833809054866, 935.4574346878794, 950.1163917556738, 969.5152423788106, 6061.969690151521, 6105.633661980295, 6111.8472038198515, 6167.506599472032, 6284.057391391328, 463.5480406603602, 469.56565177210945, 474.84804862443684, 476.37356875709025, 486.7468916163575, 3920.725549211528, 4052.371882358239, 4196.328587425997, 4201.915961680749, 4211.620954114144, 11096.61161859331, 11116.61091861781, 11568.537258509734, 11593.536258549731, 11596.0, 11315.717107072389, 11510.0, 11536.480858361227, 11547.538098476134, 11566.016888564398, 8151.470154439942, 8560.914390856136, 8575.442596231349, 8615.181557752003, 8693.608787604573, 4799.208130658542, 6080.422359875804, 6125.754969801158, 6266.467350275278, 6322.0516922462, 10347.63783267582, 10638.574457021632, 10675.946620266848, 10694.89305106939, 10701.571937122428, 9731.56207970629, 10043.949780251052, 10105.44420056904, 10598.735031623963, 10618.575256989634, 7741.535507869546, 8127.79680507992, 8415.915840841513, 8657.870131948152, 8662.35032372562, 8782.0, 8926.464412135292, 8929.910700892908, 8937.59780809878, 8945.821083578423, 7738.419618528746, 7886.171951944963, 7909.0, 8061.31478824283, 8083.555404452812, 7600.80997975055, 7729.613519323899, 7755.108162561152, 7851.489653172525, 7906.960465197867, 7265.782026539212, 7392.482526223328, 7405.888911666221, 7415.221401752954, 7427.962860185665, 4892.902141957212, 5105.0, 5105.081085404663, 5172.7155006475, 5210.739463026909, 5207.739613019409, 5217.582593392595, 5261.921071184011, 5402.135658294654, 5488.0395930711165], "value": 4743.020261288331, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.22537858955764, 310.3730464461787, 314.79066420830145, 325.1027165024532, 332.0072981784465, 4362.996510802517, 5275.868103297419, 5425.891482170359, 5451.945480545193, 5467.835964921053, 4279.282655513748, 5319.468053194679, 5347.171188465784, 5368.597355198366, 5404.1083221268445, 4144.8549300774475, 4145.751254924707, 4153.7300075494995, 4163.354680024601, 4200.222958752625, 4156.896077598054, 4170.270202714512, 4175.06061136245, 4178.937315940263, 4181.728187667807, 3158.5578019077348, 3489.185623475791, 3817.331966905794, 3966.3653815389544, 3970.483837101173, 3474.409350410436, 3507.3862074136923, 3805.41016142497, 4083.5508094109623, 4093.5906409358977, 3157.295060667237, 3574.6782789549065, 3582.247727977128, 3585.0678823505814, 3597.892063238081, 4768.427788665348, 4802.5197480252045, 5130.358705161855, 5218.321618189649, 5242.68543887368, 3803.220339830328, 3891.5524714657895, 4225.68307376948, 4562.038323520885, 4681.836135735233, 4681.836135735233, 5237.423883372826, 5641.0, 5751.181920858791, 5753.654780713171, 3634.582023067355, 3878.806059697004, 3891.3773796192754, 3900.726949113535, 3922.37242041272, 5739.368669446358, 5909.409059094056, 6018.608790428608, 6032.66820324882, 6067.332593414721, 3850.4994350734787, 3928.448262936124, 3934.6458818706456, 3934.9213015740097, 3937.488126543531, 3812.6187381262207, 4000.399940009022, 4002.299022915249, 4037.0016841663564, 4079.5920407958965, 4062.988820533855, 4115.0, 4191.371294305817, 4590.87523556724, 4694.460137084279, 5210.869728256824, 5278.947210527922, 5708.1723150143, 5815.709214539256, 5840.649561026352, 1333.2200662612481, 1363.073110284996, 1372.3344178073626, 1380.0477670407388, 1385.4042761612402, 827.668932427022, 845.6174155256077, 849.7042010933376, 866.7399780065955, 925.3337596930199, 4694.976525117421, 4735.981763920774, 4874.781304673832, 5209.114450543417, 5266.552343050884, 5316.255724198615, 5447.101228297366, 5741.770329186786, 5787.305523337226, 5789.392113828072, 4367.541408152162, 4420.756858372821, 4897.657163998486, 5222.112240919053, 5316.580955665163, 2740.862542045069, 2971.658259300165, 3084.8457577121053, 3088.5212792017273, 3105.2702614885484, 5625.0, 5683.829485115453, 5896.675682837399, 6049.304330002018, 6109.6639684817765, 5313.017091838023, 5642.463965923313, 5660.603757737002, 5973.253343332083, 6032.849178770566, 5057.949420505822, 5397.676139431568, 5758.236046697609, 6043.909341359883, 6099.817005489843, 5492.588055895744, 5541.501264886179, 5832.795852145155, 5841.575746065335, 5849.970750146393, 4598.471175814824, 5511.437181710524, 5764.279465066867, 5765.7117144141785, 5776.0, 5140.843310255163, 5923.585349025527, 5958.599729063753, 6034.969825150846, 6047.123167140652, 3889.611038896088, 3892.8832135035996, 3912.9630647878903, 3916.5104361954754, 3923.7449565778134, 5135.435102138837, 5155.613329000265, 5318.627696061239, 5864.501517371071, 5883.823485295448, 5316.059638232095, 5407.864803379947, 5665.773369065191, 5697.857553561194, 5727.255456790591, 4845.563899249085, 5112.821051263337, 5640.520555752807, 5735.684537350319, 5742.28221472316, 5080.974595127001, 5315.883664430513, 5472.157140930226, 5567.387587365468, 5579.637323574117, 4825.214670571911, 5114.795408183631, 5139.665921715226, 5181.326427564392, 5492.423295554069, 5369.1140961740875, 5454.072807622634, 5619.381867994599, 5621.695105006927, 5641.294838145232], "value": 4101.751191183962, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.8454428664978, 700.8843540815766, 730.52150841199, 734.5886303669948, 742.6212631557905, 14658.413663453463, 14951.401943922257, 15442.459513917016, 15451.922740386297, 15497.225138743073, 14061.296935153252, 14209.289535523209, 14238.0, 14255.287235638229, 14324.068935519184, 717.0964584623385, 722.9746958856441, 894.1862904756673, 908.1191244492865, 931.16660588773, 907.0158877617793, 914.7255823253031, 916.3860213656833, 917.7843206846386, 921.5023887100957, 964.4840010594334, 965.6378857928277, 965.7392504023909, 966.6906589891238, 969.1616751509964, 738.896554482373, 933.2533972821716, 942.1097063275207, 946.3281070439996, 959.0265880131637, 721.1490441279288, 722.1190148019408, 723.0925188887912, 724.0912654618472, 724.478375569589, 14880.330385132695, 14887.0, 14888.255587220596, 14936.62658433537, 14953.476628317956, 11847.881521184763, 12141.817872731915, 12253.48251887664, 12339.074569407241, 12541.372931353395, 10767.730806729816, 10965.725856853564, 11102.666919992413, 11572.189946703735, 11595.710107247301, 3500.9649903500895, 3971.721979461439, 4082.265192265391, 4173.31140361843, 4187.350960601112, 75095.62452187759, 75772.0, 76031.61984190099, 77438.6128069356, 78161.0, 7763.417743669134, 9387.483688397067, 9525.14273715369, 9613.0, 9687.70936871895, 20492.18031278762, 20518.897405513177, 20521.973901304875, 20525.58948821045, 20612.69080963787, 7451.254874512505, 7605.049368828896, 7759.961200194076, 7918.208179182151, 8038.075621303625, 3561.3767590671528, 4067.3288907330552, 4083.4283200351974, 4090.6727461802984, 4185.079282557833, 3507.2810073934784, 3583.354996100727, 3603.549556305462, 3608.476770868262, 3625.5649322081517, 539.6519245086897, 645.5029627187133, 657.6021506988203, 664.1432552007952, 682.3108660253081, 477.4461624515545, 479.8920242945309, 510.12004292594975, 515.3249243491039, 594.4887396838715, 7498.437617178624, 7992.720254791055, 8096.676132954616, 8299.460535065202, 8466.703665371682, 493.9678920870132, 517.0357283666011, 521.9925543700695, 526.1607735661613, 536.9597280204, 3852.8073596320073, 3888.96111038886, 3909.7654140751647, 3946.0, 4117.3618089196225, 93857.06142938483, 97238.51380743051, 97357.0, 99666.0, 100570.4971475138, 81404.59297703474, 81913.0, 82286.58856705678, 83516.58241708753, 84445.15554844496, 74755.0, 75796.0, 76129.9999999989, 76411.23588764042, 76548.23451765522, 7655.46411751172, 7775.455718099679, 7801.687932482637, 7833.334166595675, 7911.604419778873, 46973.530264696914, 47456.0, 48228.0, 48482.0, 48585.05659773711, 61919.761604766754, 63556.04665929922, 65652.67173664292, 66568.33431665621, 67294.32705672881, 10037.749056273651, 10088.596456141671, 10158.28891977584, 10244.282900197213, 10450.059494645518, 11190.94404527972, 11200.159988000769, 11240.887591123985, 11322.660320190407, 11337.093032557541, 10558.894411055791, 10669.786604267716, 10708.62519811834, 10742.979416955377, 10903.83644245321, 10156.136728378171, 10182.694519164437, 10463.581456741646, 10505.0, 10549.261551691308, 10337.74155646115, 10367.170626350025, 10696.465176741287, 10721.785564288826, 10739.194560408157, 8878.067802880863, 9056.864147037932, 9347.906520934703, 9398.060193980547, 9586.904130958601, 8804.779880503038, 8975.955120224357, 9062.093790620886, 9080.954595226982, 9119.0], "value": 6790.234436529797, "unit": "score"}], "name": "dromaeo_css", "value": 7270.254045262772, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 99.0, 112.0, 110.0, 112.0, 111.0, 112.0, 112.0, 111.0, 112.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 146.0, 165.0, 196.0, 125.0, 163.0, 155.0, 125.0, 206.0, 142.0], "value": 158.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 187.0, 259.0, 205.0, 185.0, 186.0, 183.0, 183.0, 180.0], "value": 193.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 56.0, 125.0, 57.0, 63.0, 64.0, 70.0, 149.0, 56.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 132.0, 79.0, 83.0, 76.0, 103.0, 80.0, 80.0, 101.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 94.0, 130.0, 136.0, 86.0, 90.0, 88.0, 90.0, 86.0], "value": 97.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 94.0, 94.0, 95.0, 94.0, 100.0, 147.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 84.0, 86.0, 82.0, 83.0, 83.0, 83.0, 83.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 58.0, 57.0, 55.0, 57.0, 57.0, 119.0, 58.0, 55.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 68.0, 73.0, 70.0, 66.0, 65.0, 65.0, 67.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 83.0, 83.0, 81.0, 84.0, 83.0, 86.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 134.0, 132.0, 136.0, 134.0, 131.0, 134.0, 216.0, 138.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 161.0, 160.0, 217.0, 225.0, 162.0, 161.0, 163.0, 160.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 89.0, 84.0, 88.0, 85.0, 63.0, 64.0, 64.0, 65.0, 65.0], "value": 74.0, "unit": "score"}], "name": "kraken", "value": 1518.4, "alertThreshold": 2.0}]} 21:20:21 INFO - Return code: 0 21:20:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:20:21 ERROR - # TBPL SUCCESS # 21:20:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:20:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:20:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:20:21 INFO - 2016-05-18 21:20:21.244 screenresolution[1809:9882] starting screenresolution argv=screenresolution get 21:20:21 INFO - 2016-05-18 21:20:21.247 screenresolution[1809:9882] Display 0: 1600x1200x32@60 21:20:21 INFO - 2016-05-18 21:20:21.253 screenresolution[1810:9885] starting screenresolution argv=screenresolution list 21:20:21 INFO - Available Modes on Display 0 21:20:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:20:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:20:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:20:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:20:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:20:21 INFO - Intel Iris: 21:20:21 INFO - Chipset Model: Intel Iris 21:20:21 INFO - Type: GPU 21:20:21 INFO - Bus: Built-In 21:20:21 INFO - VRAM (Dynamic, Max): 1536 MB 21:20:21 INFO - Vendor: Intel (0x8086) 21:20:21 INFO - Device ID: 0x0a2e 21:20:21 INFO - Revision ID: 0x0009 21:20:21 INFO - Displays: 21:20:21 INFO - Display: 21:20:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:20:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:20:21 INFO - Display Serial Number: 1600x1200 60 21:20:21 INFO - Main Display: Yes 21:20:21 INFO - Mirror: Off 21:20:21 INFO - Online: Yes 21:20:21 INFO - Rotation: Supported 21:20:21 INFO - Return code: 0 21:20:21 INFO - Running post-action listener: _resource_record_post_action 21:20:21 INFO - ##### 21:20:21 INFO - ##### Finished run-tests step (success) 21:20:21 INFO - ##### 21:20:21 INFO - Running post-run listener: _resource_record_post_run 21:20:21 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 36951552; Write bytes: 696112640; Read time: 238; Write time: 1970 21:20:21 INFO - TinderboxPrint: CPU usage
53.7% 21:20:21 INFO - TinderboxPrint: I/O read bytes / time
36,951,552 / 238 21:20:21 INFO - TinderboxPrint: I/O write bytes / time
696,112,640 / 1,970 21:20:21 INFO - TinderboxPrint: CPU idle
2,247.1 (46.3%) 21:20:21 INFO - TinderboxPrint: CPU system
968.4 (20.0%) 21:20:21 INFO - TinderboxPrint: CPU user
1,638.0 (33.7%) 21:20:21 INFO - TinderboxPrint: Swap in / out
432,562,176 / 0 21:20:21 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385303040; Write bytes: 378742272; Read time: 29417; Write time: 770 21:20:21 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35194368; Write bytes: 297857024; Read time: 225; Write time: 1151 21:20:21 INFO - Running post-run listener: _upload_blobber_files 21:20:21 INFO - Blob upload gear active. 21:20:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:20:21 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 21:20:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:20:22 INFO - (blobuploader) - INFO - Open directory for files ... 21:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:20:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:23 INFO - (blobuploader) - INFO - Done attempting. 21:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:20:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:20:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:24 INFO - (blobuploader) - INFO - Done attempting. 21:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:20:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:26 INFO - (blobuploader) - INFO - Done attempting. 21:20:26 INFO - (blobuploader) - INFO - Iteration through files over. 21:20:26 INFO - Return code: 0 21:20:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:20:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:20:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd0e79c22fb28f2fe74ad28b7b8143f6f592c0fe26a90a6c4f2e66bab451a2b7ae4dfc588ee24d391a5504dc610be586514996980f8e59550eaf783af976a30e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59ce1b13578f3d3b93696ff4ba598d2bfea4e74d9264acdf7199240399d09ce4fd4609cfb72b3032c73456010e883959020f5b3f50c2a70438c5f38f9f413bac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e75637750f538c2ac9290a29569e3ef492383ea453bc8981b3b0cf7595bdf9adba4836fa14a4f2fecf228e7ca730466c95438ef12aac21bbadf266e238957a3"} 21:20:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:20:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:20:26 INFO - Contents: 21:20:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd0e79c22fb28f2fe74ad28b7b8143f6f592c0fe26a90a6c4f2e66bab451a2b7ae4dfc588ee24d391a5504dc610be586514996980f8e59550eaf783af976a30e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59ce1b13578f3d3b93696ff4ba598d2bfea4e74d9264acdf7199240399d09ce4fd4609cfb72b3032c73456010e883959020f5b3f50c2a70438c5f38f9f413bac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e75637750f538c2ac9290a29569e3ef492383ea453bc8981b3b0cf7595bdf9adba4836fa14a4f2fecf228e7ca730466c95438ef12aac21bbadf266e238957a3"} 21:20:26 INFO - Running post-run listener: copy_logs_to_upload_dir 21:20:26 INFO - Copying logs to upload dir... 21:20:26 INFO - mkdir: /builds/slave/test/build/upload/logs 21:20:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.568515 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-18 21:20:26.704734) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 21:20:26.707899) ========= 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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd0e79c22fb28f2fe74ad28b7b8143f6f592c0fe26a90a6c4f2e66bab451a2b7ae4dfc588ee24d391a5504dc610be586514996980f8e59550eaf783af976a30e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59ce1b13578f3d3b93696ff4ba598d2bfea4e74d9264acdf7199240399d09ce4fd4609cfb72b3032c73456010e883959020f5b3f50c2a70438c5f38f9f413bac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e75637750f538c2ac9290a29569e3ef492383ea453bc8981b3b0cf7595bdf9adba4836fa14a4f2fecf228e7ca730466c95438ef12aac21bbadf266e238957a3"} build_url:https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011446 build_url: 'https://queue.taskcluster.net/v1/task/Y8KCiiBpTumIOTHO7Qe9PQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd0e79c22fb28f2fe74ad28b7b8143f6f592c0fe26a90a6c4f2e66bab451a2b7ae4dfc588ee24d391a5504dc610be586514996980f8e59550eaf783af976a30e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59ce1b13578f3d3b93696ff4ba598d2bfea4e74d9264acdf7199240399d09ce4fd4609cfb72b3032c73456010e883959020f5b3f50c2a70438c5f38f9f413bac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e75637750f538c2ac9290a29569e3ef492383ea453bc8981b3b0cf7595bdf9adba4836fa14a4f2fecf228e7ca730466c95438ef12aac21bbadf266e238957a3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 21:20:26.736443) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 21:20:26.736766) ========= 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.tdJKxOgj9b/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.NMDEYvuyfM/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005288 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 21:20:26.784366) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 21:20:26.787723) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 21:20:26.788076) ========= ========= Total master_lag: 0.15 =========