builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0340 starttime: 1463407630.03 results: success (0) buildid: 20160516050634 builduid: df246aded826448b938d9323b0037136 revision: 3b86a9c8c8527db36b8d8681700ca65c996b12bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.027855) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.028446) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.028729) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.095016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.095318) ========= 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.DyeZAvn5HD/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.GDo9Ko82au/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-16 07:07:10-- 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% 57.3M=0s 2016-05-16 07:07:10 (57.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.848568 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.965477) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:10.965778) ========= 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.DyeZAvn5HD/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.GDo9Ko82au/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.019963 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.014463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.014826) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3b86a9c8c8527db36b8d8681700ca65c996b12bb --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 3b86a9c8c8527db36b8d8681700ca65c996b12bb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DyeZAvn5HD/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.GDo9Ko82au/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-16 07:07:11,090 truncating revision to first 12 chars 2016-05-16 07:07:11,090 Setting DEBUG logging. 2016-05-16 07:07:11,090 attempt 1/10 2016-05-16 07:07:11,090 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3b86a9c8c852?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 07:07:11,396 unpacking tar archive at: fx-team-3b86a9c8c852/testing/mozharness/ program finished with exit code 0 elapsedTime=0.550910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.582952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.583267) ========= script_repo_revision: 3b86a9c8c8527db36b8d8681700ca65c996b12bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.583711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.583982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 07:07:11.598258) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-16 07:07:11.598830) ========= /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.DyeZAvn5HD/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.GDo9Ko82au/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:07:11 INFO - MultiFileLogger online at 20160516 07:07:11 in /builds/slave/test 07:07:11 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 07:07:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:07:11 INFO - {'append_to_log': False, 07:07:11 INFO - 'base_work_dir': '/builds/slave/test', 07:07:11 INFO - 'blob_upload_branch': 'Fx-Team', 07:07:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:07:11 INFO - 'branch': 'Fx-Team', 07:07:11 INFO - 'buildbot_json_path': 'buildprops.json', 07:07:11 INFO - 'config_files': ('talos/mac_config.py',), 07:07:11 INFO - 'default_actions': ('clobber', 07:07:11 INFO - 'read-buildbot-config', 07:07:11 INFO - 'download-and-extract', 07:07:11 INFO - 'populate-webroot', 07:07:11 INFO - 'create-virtualenv', 07:07:11 INFO - 'install', 07:07:11 INFO - 'run-tests'), 07:07:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:07:11 INFO - 'download_minidump_stackwalk': True, 07:07:11 INFO - 'download_symbols': 'ondemand', 07:07:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:07:11 INFO - 'tooltool.py': '/tools/tooltool.py', 07:07:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:07:11 INFO - '/tools/misc-python/virtualenv.py')}, 07:07:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:07:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:07:11 INFO - 'installer_path': 'installer.exe', 07:07:11 INFO - 'log_level': 'info', 07:07:11 INFO - 'log_name': 'talos', 07:07:11 INFO - 'log_to_console': True, 07:07:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:07:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:07:11 INFO - 'opt_config_files': (), 07:07:11 INFO - 'pip_index': False, 07:07:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:07:11 INFO - 'cmd': ('bash', 07:07:11 INFO - '-c', 07:07:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:07:11 INFO - 'enabled': True, 07:07:11 INFO - 'halt_on_failure': False, 07:07:11 INFO - 'name': 'check_screen_resolution'},), 07:07:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:07:11 INFO - 'cmd': ('bash', 07:07:11 INFO - '-c', 07:07:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:07:11 INFO - 'enabled': True, 07:07:11 INFO - 'halt_on_failure': False, 07:07:11 INFO - 'name': 'check_screen_resolution'},), 07:07:11 INFO - 'run_cmd_checks_enabled': True, 07:07:11 INFO - 'sps_profile': False, 07:07:11 INFO - 'sps_profile_interval': 0, 07:07:11 INFO - 'suite': 'dromaeojs', 07:07:11 INFO - 'system_bits': '32', 07:07:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:07:11 INFO - 'title': 't-yosemite-r7-0340', 07:07:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:07:11 INFO - 'use_talos_json': True, 07:07:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:07:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:07:11 INFO - 'work_dir': 'build'} 07:07:11 INFO - ##### 07:07:11 INFO - ##### Running clobber step. 07:07:11 INFO - ##### 07:07:11 INFO - Running pre-action listener: _resource_record_pre_action 07:07:11 INFO - Running main action method: clobber 07:07:11 INFO - rmtree: /builds/slave/test/build 07:07:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:07:13 INFO - Running post-action listener: _resource_record_post_action 07:07:13 INFO - ##### 07:07:13 INFO - ##### Finished clobber step (success) 07:07:13 INFO - ##### 07:07:13 INFO - ##### 07:07:13 INFO - ##### Running read-buildbot-config step. 07:07:13 INFO - ##### 07:07:13 INFO - Running pre-action listener: _resource_record_pre_action 07:07:13 INFO - Running main action method: read_buildbot_config 07:07:13 INFO - Using buildbot properties: 07:07:13 INFO - { 07:07:13 INFO - "project": "", 07:07:13 INFO - "product": "firefox", 07:07:13 INFO - "script_repo_revision": "production", 07:07:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:07:13 INFO - "repository": "", 07:07:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:07:13 INFO - "buildid": "20160516050634", 07:07:13 INFO - "pgo_build": "False", 07:07:13 INFO - "basedir": "/builds/slave/test", 07:07:13 INFO - "buildnumber": 775, 07:07:13 INFO - "slavename": "t-yosemite-r7-0340", 07:07:13 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:07:13 INFO - "platform": "yosemite_r7", 07:07:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:07:13 INFO - "branch": "fx-team", 07:07:13 INFO - "repo_path": "integration/fx-team", 07:07:13 INFO - "revision": "3b86a9c8c8527db36b8d8681700ca65c996b12bb", 07:07:13 INFO - "stage_platform": "macosx64", 07:07:13 INFO - "builduid": "df246aded826448b938d9323b0037136", 07:07:13 INFO - "slavebuilddir": "test" 07:07:13 INFO - } 07:07:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:07:13 INFO - Running post-action listener: _resource_record_post_action 07:07:13 INFO - ##### 07:07:13 INFO - ##### Finished read-buildbot-config step (success) 07:07:13 INFO - ##### 07:07:13 INFO - ##### 07:07:13 INFO - ##### Running download-and-extract step. 07:07:13 INFO - ##### 07:07:13 INFO - Running pre-action listener: _resource_record_pre_action 07:07:13 INFO - Running main action method: download_and_extract 07:07:13 INFO - mkdir: /builds/slave/test/build/tests 07:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:13 INFO - https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:07:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:07:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:07:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:28 INFO - Downloaded 1199 bytes. 07:07:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:07:28 INFO - Using the following test package requirements: 07:07:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:07:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:07:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:07:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'jsshell-mac.zip'], 07:07:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:07:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:07:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:07:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:07:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:07:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:07:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:07:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:07: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')]} 07:07:28 INFO - https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:07:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:07:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:07:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:30 INFO - Downloaded 18267719 bytes. 07:07:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:07:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:07:33 INFO - Return code: 0 07:07:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:33 INFO - https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:07:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:07:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:07:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:39 INFO - Downloaded 18267719 bytes. 07:07:39 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'] 07:07:39 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 07:07:43 INFO - Return code: 0 07:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:07:43 INFO - https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:07:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:07:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:07:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:45 INFO - Downloaded 10840194 bytes. 07:07:45 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'] 07:07:45 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 07:07:45 INFO - Return code: 0 07:07:45 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')]} 07:07:45 INFO - https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:07:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:07:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:07:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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 07:07:52 INFO - Downloaded 110057105 bytes. 07:07:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:07:52 INFO - mkdir: /builds/slave/test/properties 07:07:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:07:52 INFO - Writing to file /builds/slave/test/properties/build_url 07:07:52 INFO - Contents: 07:07:52 INFO - build_url:https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:07:54 INFO - Running post-action listener: _resource_record_post_action 07:07:54 INFO - Running post-action listener: set_extra_try_arguments 07:07:54 INFO - ##### 07:07:54 INFO - ##### Finished download-and-extract step (success) 07:07:54 INFO - ##### 07:07:54 INFO - ##### 07:07:54 INFO - ##### Running populate-webroot step. 07:07:54 INFO - ##### 07:07:54 INFO - Running pre-action listener: _resource_record_pre_action 07:07:54 INFO - Running main action method: populate_webroot 07:07:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:07:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:07:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:07:54 INFO - '--tppagecycles', 07:07:54 INFO - '7'], 07:07:54 INFO - 'tests': ['tsvgm']}}, 07:07:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:07:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:07:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:07:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:07:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['damp', 'tps']}, 07:07:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'tests': ['damp', 'tps']}, 07:07:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['dromaeo_dom']}, 07:07:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:07:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['a11yr', 07:07:54 INFO - 'ts_paint', 07:07:54 INFO - 'tpaint', 07:07:54 INFO - 'sessionrestore', 07:07:54 INFO - 'sessionrestore_no_auto_restore', 07:07:54 INFO - 'tabpaint']}, 07:07:54 INFO - 'other-e10s': {'tests': ['a11yr', 07:07:54 INFO - 'ts_paint', 07:07:54 INFO - 'tpaint', 07:07:54 INFO - 'sessionrestore', 07:07:54 INFO - 'sessionrestore_no_auto_restore', 07:07:54 INFO - 'tabpaint']}, 07:07:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:07:54 INFO - 'ts_paint', 07:07:54 INFO - 'tpaint', 07:07:54 INFO - 'sessionrestore', 07:07:54 INFO - 'sessionrestore_no_auto_restore', 07:07:54 INFO - 'tabpaint']}, 07:07:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:07:54 INFO - 'ts_paint', 07:07:54 INFO - 'tpaint', 07:07:54 INFO - 'sessionrestore', 07:07:54 INFO - 'sessionrestore_no_auto_restore', 07:07:54 INFO - 'tabpaint']}, 07:07:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['a11yr', 07:07:54 INFO - 'ts_paint', 07:07:54 INFO - 'tpaint', 07:07:54 INFO - 'sessionrestore', 07:07:54 INFO - 'sessionrestore_no_auto_restore', 07:07:54 INFO - 'tabpaint']}, 07:07:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['a11yr', 07:07:54 INFO - 'ts_paint', 07:07:54 INFO - 'tpaint', 07:07:54 INFO - 'sessionrestore', 07:07:54 INFO - 'sessionrestore_no_auto_restore', 07:07:54 INFO - 'tabpaint']}, 07:07:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['tsvgx', 07:07:54 INFO - 'tsvgr_opacity', 07:07:54 INFO - 'tart', 07:07:54 INFO - 'tscrollx', 07:07:54 INFO - 'cart']}, 07:07:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:07:54 INFO - 'tsvgr_opacity', 07:07:54 INFO - 'tart', 07:07:54 INFO - 'tscrollx', 07:07:54 INFO - 'cart']}, 07:07:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'talos_options': ['--disable-e10s'], 07:07:54 INFO - 'tests': ['tp5o']}, 07:07:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'tests': ['tp5o']}, 07:07:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'talos_options': ['--disable-e10s', 07:07:54 INFO - '--xperf_path', 07:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:54 INFO - 'tests': ['tp5n']}, 07:07:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:54 INFO - 'talos_options': ['--xperf_path', 07:07:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:54 INFO - 'tests': ['tp5n']}}, 07:07:54 INFO - 'talos.zip': {'path': '', 07:07:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:07:54 INFO - Running post-action listener: _resource_record_post_action 07:07:54 INFO - ##### 07:07:54 INFO - ##### Finished populate-webroot step (success) 07:07:54 INFO - ##### 07:07:54 INFO - ##### 07:07:54 INFO - ##### Running create-virtualenv step. 07:07:54 INFO - ##### 07:07:54 INFO - Running pre-action listener: _resource_record_pre_action 07:07:54 INFO - Running main action method: create_virtualenv 07:07:54 INFO - Creating virtualenv /builds/slave/test/build/venv 07:07:54 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 07:07:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:07:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:07:54 INFO - Using real prefix '/tools/python27' 07:07:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:07:55 INFO - Installing distribute.............................................................................................................................................................................................done. 07:07:58 INFO - Installing pip.................done. 07:07:58 INFO - Return code: 0 07:07:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:07:58 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')]} 07:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:58 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')]} 07:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:58 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:07:58 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 07:07:58 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 07:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:58 INFO - 'HOME': '/Users/cltbld', 07:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:58 INFO - 'LOGNAME': 'cltbld', 07:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:58 INFO - 'MOZ_NO_REMOTE': '1', 07:07:58 INFO - 'NO_EM_RESTART': '1', 07:07:58 INFO - 'PAGER': '/bin/cat', 07:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:58 INFO - 'PWD': '/builds/slave/test', 07:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:58 INFO - 'SHELL': '/bin/bash', 07:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:07:58 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:58 INFO - 'USER': 'cltbld', 07:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:58 INFO - 'XPC_FLAGS': '0x0', 07:07:58 INFO - 'XPC_SERVICE_NAME': '0', 07:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:58 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 07:07:58 INFO - Cleaning up... 07:07:58 INFO - Return code: 0 07:07:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:07:58 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')]} 07:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:58 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')]} 07:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:58 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:07:58 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 07:07:58 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 07:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:58 INFO - 'HOME': '/Users/cltbld', 07:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:58 INFO - 'LOGNAME': 'cltbld', 07:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:58 INFO - 'MOZ_NO_REMOTE': '1', 07:07:58 INFO - 'NO_EM_RESTART': '1', 07:07:58 INFO - 'PAGER': '/bin/cat', 07:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:58 INFO - 'PWD': '/builds/slave/test', 07:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:58 INFO - 'SHELL': '/bin/bash', 07:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:07:58 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:58 INFO - 'USER': 'cltbld', 07:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:58 INFO - 'XPC_FLAGS': '0x0', 07:07:58 INFO - 'XPC_SERVICE_NAME': '0', 07:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:58 INFO - Downloading/unpacking psutil>=3.1.1 07:07:58 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 07:07:58 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 07:07:58 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 07:07:58 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 07:07:58 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 07:07:58 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 07:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:08:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:08:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:08:01 INFO - Installing collected packages: psutil 07:08:01 INFO - Running setup.py install for psutil 07:08:01 INFO - building 'psutil._psutil_osx' extension 07:08:01 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 07:08:01 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 07:08:01 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 07:08:02 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 07:08:02 INFO - building 'psutil._psutil_posix' extension 07:08:02 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 07:08:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:08:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:08:02 INFO - ^ 07:08:02 INFO - 1 warning generated. 07:08:02 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 07:08:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:08:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:08:02 INFO - Successfully installed psutil 07:08:02 INFO - Cleaning up... 07:08:02 INFO - Return code: 0 07:08:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:08: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')]} 07:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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')]} 07:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:08: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 mozsystemmonitor==0.3 07:08:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:02 INFO - 'HOME': '/Users/cltbld', 07:08:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:02 INFO - 'LOGNAME': 'cltbld', 07:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:02 INFO - 'MOZ_NO_REMOTE': '1', 07:08:02 INFO - 'NO_EM_RESTART': '1', 07:08:02 INFO - 'PAGER': '/bin/cat', 07:08:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:02 INFO - 'PWD': '/builds/slave/test', 07:08:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:02 INFO - 'SHELL': '/bin/bash', 07:08:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:02 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:02 INFO - 'USER': 'cltbld', 07:08:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:02 INFO - 'XPC_FLAGS': '0x0', 07:08:02 INFO - 'XPC_SERVICE_NAME': '0', 07:08:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:08:05 INFO - Installing collected packages: mozsystemmonitor 07:08:05 INFO - Running setup.py install for mozsystemmonitor 07:08:05 INFO - Successfully installed mozsystemmonitor 07:08:05 INFO - Cleaning up... 07:08:05 INFO - Return code: 0 07:08:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:05 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08:05 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 07:08:05 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 07:08:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:05 INFO - 'HOME': '/Users/cltbld', 07:08:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:05 INFO - 'LOGNAME': 'cltbld', 07:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:05 INFO - 'MOZ_NO_REMOTE': '1', 07:08:05 INFO - 'NO_EM_RESTART': '1', 07:08:05 INFO - 'PAGER': '/bin/cat', 07:08:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:05 INFO - 'PWD': '/builds/slave/test', 07:08:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:05 INFO - 'SHELL': '/bin/bash', 07:08:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:05 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:05 INFO - 'USER': 'cltbld', 07:08:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:05 INFO - 'XPC_FLAGS': '0x0', 07:08:05 INFO - 'XPC_SERVICE_NAME': '0', 07:08:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:05 INFO - Downloading/unpacking blobuploader==1.2.4 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08: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 07:08:08 INFO - Downloading blobuploader-1.2.4.tar.gz 07:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:08:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:08:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:09 INFO - Downloading docopt-0.6.1.tar.gz 07:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:08:09 INFO - Installing collected packages: blobuploader, requests, docopt 07:08:09 INFO - Running setup.py install for blobuploader 07:08:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:08:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:08:09 INFO - Running setup.py install for requests 07:08:09 INFO - Running setup.py install for docopt 07:08:10 INFO - Successfully installed blobuploader requests docopt 07:08:10 INFO - Cleaning up... 07:08:10 INFO - Return code: 0 07:08:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:10 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')]} 07:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:10 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')]} 07:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:10 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08:10 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 07:08:10 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 07:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:10 INFO - 'HOME': '/Users/cltbld', 07:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:10 INFO - 'LOGNAME': 'cltbld', 07:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:10 INFO - 'MOZ_NO_REMOTE': '1', 07:08:10 INFO - 'NO_EM_RESTART': '1', 07:08:10 INFO - 'PAGER': '/bin/cat', 07:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:10 INFO - 'PWD': '/builds/slave/test', 07:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:10 INFO - 'SHELL': '/bin/bash', 07:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:10 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:10 INFO - 'USER': 'cltbld', 07:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:10 INFO - 'XPC_FLAGS': '0x0', 07:08:10 INFO - 'XPC_SERVICE_NAME': '0', 07:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:08:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-R11mmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:08:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-yaX_aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:08:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-e_pakU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:08:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-k20HRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:08:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-dfurix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:08:10 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-viCh9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9DrBew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-hRlkd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9kTAlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-NepeeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-b8YoOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-WHgbok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-auekAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-DRl6E7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-OYSIBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:08:11 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Y_fYgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:08:12 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-UPWOP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:08:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:08:12 INFO - Running setup.py install for manifestparser 07:08:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:08:12 INFO - Running setup.py install for mozcrash 07:08:12 INFO - Running setup.py install for mozdebug 07:08:12 INFO - Running setup.py install for mozdevice 07:08:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:08:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:08:12 INFO - Running setup.py install for mozfile 07:08:12 INFO - Running setup.py install for mozhttpd 07:08:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Running setup.py install for mozinfo 07:08:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Running setup.py install for mozInstall 07:08:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Running setup.py install for mozleak 07:08:13 INFO - Running setup.py install for mozlog 07:08:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Running setup.py install for moznetwork 07:08:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:08:13 INFO - Running setup.py install for mozprocess 07:08:13 INFO - Running setup.py install for mozprofile 07:08:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:08:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:08:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:08:14 INFO - Running setup.py install for mozrunner 07:08:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:08:14 INFO - Running setup.py install for mozscreenshot 07:08:14 INFO - Running setup.py install for moztest 07:08:14 INFO - Running setup.py install for mozversion 07:08:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:08:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:08:14 INFO - Cleaning up... 07:08:14 INFO - Return code: 0 07:08:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:15 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08:15 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 07:08:15 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 07:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:15 INFO - 'HOME': '/Users/cltbld', 07:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:15 INFO - 'LOGNAME': 'cltbld', 07:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:15 INFO - 'MOZ_NO_REMOTE': '1', 07:08:15 INFO - 'NO_EM_RESTART': '1', 07:08:15 INFO - 'PAGER': '/bin/cat', 07:08:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:15 INFO - 'PWD': '/builds/slave/test', 07:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:15 INFO - 'SHELL': '/bin/bash', 07:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:15 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:15 INFO - 'USER': 'cltbld', 07:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:15 INFO - 'XPC_FLAGS': '0x0', 07:08:15 INFO - 'XPC_SERVICE_NAME': '0', 07:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:08:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Jc1ZQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:08:15 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)) 07:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:08:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-voD_9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:08:15 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)) 07:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:08:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-IiZZcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:08:15 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)) 07:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:08:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rfP8NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:08:15 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)) 07:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:08:15 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-HFWllR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-0BnMBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-T_PXLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-zDUSiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-fZp_sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9x3Afc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-bWEt3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-wyM5EM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-x23hIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:08:16 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)) 07:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:08:16 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-zzRNVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:08:17 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)) 07:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:17 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Sy7C8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:17 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)) 07:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:08:17 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-pyTFni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:08:17 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)) 07:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:08:17 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-do6hHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:08:17 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)) 07:08:17 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)) 07:08:17 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)) 07:08:17 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)) 07:08:17 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)) 07:08:17 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)) 07:08:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:20 INFO - Downloading blessings-1.6.tar.gz 07:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:08:20 INFO - Installing collected packages: blessings 07:08:20 INFO - Running setup.py install for blessings 07:08:20 INFO - Successfully installed blessings 07:08:20 INFO - Cleaning up... 07:08:20 INFO - Return code: 0 07:08:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:08:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:08:20 INFO - Reading from file tmpfile_stdout 07:08:20 INFO - Current package versions: 07:08:20 INFO - blessings == 1.6 07:08:20 INFO - blobuploader == 1.2.4 07:08:20 INFO - docopt == 0.6.1 07:08:20 INFO - manifestparser == 1.1 07:08:20 INFO - mozInstall == 1.12 07:08:20 INFO - mozcrash == 0.17 07:08:20 INFO - mozdebug == 0.1 07:08:20 INFO - mozdevice == 0.48 07:08:20 INFO - mozfile == 1.2 07:08:20 INFO - mozhttpd == 0.7 07:08:20 INFO - mozinfo == 0.9 07:08:20 INFO - mozleak == 0.1 07:08:20 INFO - mozlog == 3.2 07:08:20 INFO - moznetwork == 0.27 07:08:20 INFO - mozprocess == 0.22 07:08:20 INFO - mozprofile == 0.28 07:08:20 INFO - mozrunner == 6.11 07:08:20 INFO - mozscreenshot == 0.1 07:08:20 INFO - mozsystemmonitor == 0.3 07:08:20 INFO - moztest == 0.7 07:08:20 INFO - mozversion == 1.4 07:08:20 INFO - psutil == 3.1.1 07:08:20 INFO - requests == 1.2.3 07:08:20 INFO - wsgiref == 0.1.2 07:08:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:20 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08:20 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 07:08:20 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 07:08:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:20 INFO - 'HOME': '/Users/cltbld', 07:08:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:20 INFO - 'LOGNAME': 'cltbld', 07:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:20 INFO - 'MOZ_NO_REMOTE': '1', 07:08:20 INFO - 'NO_EM_RESTART': '1', 07:08:20 INFO - 'PAGER': '/bin/cat', 07:08:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:20 INFO - 'PWD': '/builds/slave/test', 07:08:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:20 INFO - 'SHELL': '/bin/bash', 07:08:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:20 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:20 INFO - 'USER': 'cltbld', 07:08:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:20 INFO - 'XPC_FLAGS': '0x0', 07:08:20 INFO - 'XPC_SERVICE_NAME': '0', 07:08:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 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)) 07:08:21 INFO - Cleaning up... 07:08:21 INFO - Return code: 0 07:08:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:08: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')]} 07:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08: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')]} 07:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:21 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08:21 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 07:08:21 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 07:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:21 INFO - 'HOME': '/Users/cltbld', 07:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:21 INFO - 'LOGNAME': 'cltbld', 07:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:21 INFO - 'MOZ_NO_REMOTE': '1', 07:08:21 INFO - 'NO_EM_RESTART': '1', 07:08:21 INFO - 'PAGER': '/bin/cat', 07:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:21 INFO - 'PWD': '/builds/slave/test', 07:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:21 INFO - 'SHELL': '/bin/bash', 07:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:21 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:21 INFO - 'USER': 'cltbld', 07:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:21 INFO - 'XPC_FLAGS': '0x0', 07:08:21 INFO - 'XPC_SERVICE_NAME': '0', 07:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:21 INFO - Downloading/unpacking jsonschema 07:08:21 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 07:08:21 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 07:08:21 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 07:08:21 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 07:08:21 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 07:08:21 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 07:08:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:08:23 INFO - Downloading/unpacking functools32 (from jsonschema) 07:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:24 INFO - Downloading functools32-3.2.3-2.tar.gz 07:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:08:24 INFO - warning: no files found matching '*.txt' 07:08:24 INFO - no previously-included directories found matching 'build' 07:08:24 INFO - no previously-included directories found matching 'dist' 07:08:24 INFO - no previously-included directories found matching '.git*' 07:08:24 INFO - Installing collected packages: jsonschema, functools32 07:08:24 INFO - Running setup.py install for functools32 07:08:24 INFO - warning: no files found matching '*.txt' 07:08:24 INFO - no previously-included directories found matching 'build' 07:08:24 INFO - no previously-included directories found matching 'dist' 07:08:24 INFO - no previously-included directories found matching '.git*' 07:08:24 INFO - Successfully installed jsonschema functools32 07:08:24 INFO - Cleaning up... 07:08:24 INFO - Return code: 0 07:08:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:08:24 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')]} 07:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:24 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')]} 07:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:24 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 0x104a24a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a2a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff470d7ae90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, '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.DyeZAvn5HD/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.GDo9Ko82au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 07:08:24 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 07:08:24 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 07:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:08:24 INFO - 'HOME': '/Users/cltbld', 07:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:08:24 INFO - 'LOGNAME': 'cltbld', 07:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:24 INFO - 'MOZ_NO_REMOTE': '1', 07:08:24 INFO - 'NO_EM_RESTART': '1', 07:08:24 INFO - 'PAGER': '/bin/cat', 07:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:24 INFO - 'PWD': '/builds/slave/test', 07:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:08:24 INFO - 'SHELL': '/bin/bash', 07:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:08:24 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:08:24 INFO - 'USER': 'cltbld', 07:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:24 INFO - 'XPC_FLAGS': '0x0', 07:08:24 INFO - 'XPC_SERVICE_NAME': '0', 07:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:24 INFO - Downloading/unpacking flake8 07:08:24 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 07:08:24 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 07:08:24 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 07:08:24 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 07:08:24 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 07:08:24 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 07:08:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:08:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:08:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:27 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 07:08:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:08:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:08:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:08:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:08:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:08:28 INFO - no previously-included directories found matching 'docs/_build' 07:08:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:08:28 INFO - Running setup.py install for pep8 07:08:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:08:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:08:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:08:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:08:28 INFO - no previously-included directories found matching 'docs/_build' 07:08:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:08:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:08:28 INFO - Cleaning up... 07:08:28 INFO - Return code: 0 07:08:28 INFO - Running post-action listener: _resource_record_post_action 07:08:28 INFO - Running post-action listener: _start_resource_monitoring 07:08:28 INFO - Starting resource monitoring. 07:08:28 INFO - ##### 07:08:28 INFO - ##### Finished create-virtualenv step (success) 07:08:28 INFO - ##### 07:08:28 INFO - ##### 07:08:28 INFO - ##### Running install step. 07:08:28 INFO - ##### 07:08:28 INFO - Running pre-action listener: _resource_record_pre_action 07:08:28 INFO - Running main action method: install 07:08:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:08:29 INFO - Reading from file tmpfile_stdout 07:08:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:08:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:08:29 INFO - Reading from file tmpfile_stdout 07:08:29 INFO - Output received: 07:08:29 INFO - Usage: mozinstall [options] installer 07:08:29 INFO - Options: 07:08:29 INFO - -h, --help show this help message and exit 07:08:29 INFO - -d DEST, --destination=DEST 07:08:29 INFO - Directory to install application into. [default: 07:08:29 INFO - "/builds/slave/test"] 07:08:29 INFO - --app=APP Application being installed. [default: firefox] 07:08:29 INFO - mkdir: /builds/slave/test/build/application 07:08:29 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'] 07:08:29 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 07:09:02 INFO - Reading from file tmpfile_stdout 07:09:02 INFO - Output received: 07:09:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:09:02 INFO - Running post-action listener: _resource_record_post_action 07:09:02 INFO - ##### 07:09:02 INFO - ##### Finished install step (success) 07:09:02 INFO - ##### 07:09:02 INFO - ##### 07:09:02 INFO - ##### Running run-tests step. 07:09:02 INFO - ##### 07:09:02 INFO - Running pre-action listener: _resource_record_pre_action 07:09:02 INFO - Running main action method: run_tests 07:09:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:09:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:09:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:09:02 INFO - 2016-05-16 07:09:02.650 screenresolution[2846:53820] starting screenresolution argv=screenresolution get 07:09:02 INFO - 2016-05-16 07:09:02.652 screenresolution[2846:53820] Display 0: 1600x1200x32@60 07:09:02 INFO - 2016-05-16 07:09:02.658 screenresolution[2847:53822] starting screenresolution argv=screenresolution list 07:09:02 INFO - Available Modes on Display 0 07:09:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:09:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:09:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:09:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:09:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:09:02 INFO - Intel Iris: 07:09:02 INFO - Chipset Model: Intel Iris 07:09:02 INFO - Type: GPU 07:09:02 INFO - Bus: Built-In 07:09:02 INFO - VRAM (Dynamic, Max): 1536 MB 07:09:02 INFO - Vendor: Intel (0x8086) 07:09:02 INFO - Device ID: 0x0a2e 07:09:02 INFO - Revision ID: 0x0009 07:09:02 INFO - Displays: 07:09:02 INFO - Display: 07:09:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:09:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:09:02 INFO - Display Serial Number: 1600x1200 60 07:09:02 INFO - Main Display: Yes 07:09:02 INFO - Mirror: Off 07:09:02 INFO - Online: Yes 07:09:02 INFO - Rotation: Supported 07:09:02 INFO - Return code: 0 07:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:09:02 INFO - Python 2.7.3 07:09:02 INFO - Return code: 0 07:09:02 INFO - grabbing minidump binary from tooltool 07:09: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')]} 07:09:02 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 0x1048c8d30>, '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 0x1046ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:09:02 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 07:09:02 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 07:09:02 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 07:09:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:09:03 INFO - Return code: 0 07:09:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:09:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:09:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:09:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:09:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:09:04 INFO - Return code: 0 07:09:04 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/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 07:09:04 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/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0340 --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 07:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DyeZAvn5HD/Render', 07:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:09:04 INFO - 'HOME': '/Users/cltbld', 07:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:09:04 INFO - 'LOGNAME': 'cltbld', 07:09:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:09:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:09:04 INFO - 'MOZ_NO_REMOTE': '1', 07:09:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:09:04 INFO - 'NO_EM_RESTART': '1', 07:09:04 INFO - 'PAGER': '/bin/cat', 07:09:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:09:04 INFO - 'PWD': '/builds/slave/test', 07:09:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:09:04 INFO - 'SHELL': '/bin/bash', 07:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDo9Ko82au/Listeners', 07:09:04 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 07:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:09:04 INFO - 'USER': 'cltbld', 07:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:09:04 INFO - 'XPC_FLAGS': '0x0', 07:09:04 INFO - 'XPC_SERVICE_NAME': '0', 07:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:09:04 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/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 07:09:04 INFO - mozversion application_buildid: 20160516050634 07:09:04 INFO - mozversion application_changeset: 3b86a9c8c8527db36b8d8681700ca65c996b12bb 07:09:04 INFO - mozversion application_display_name: Nightly 07:09:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:09:04 INFO - mozversion application_name: Firefox 07:09:04 INFO - mozversion application_remotingname: firefox 07:09:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:09:04 INFO - mozversion application_vendor: Mozilla 07:09:04 INFO - mozversion application_version: 49.0a1 07:09:04 INFO - mozversion platform_buildid: 20160516050634 07:09:04 INFO - mozversion platform_changeset: 3b86a9c8c8527db36b8d8681700ca65c996b12bb 07:09:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:09:04 INFO - mozversion platform_version: 49.0a1 07:09:04 INFO - using testdate: 1463407744 07:09:04 INFO - actual date: 1463407744 07:09:04 INFO - starting webserver on 'localhost:60710' 07:09:04 INFO - SUITE-START | Running 2 tests 07:09:04 INFO - TEST-START | dromaeo_css 07:09:04 INFO - operating with platform_type : mac_ 07:09:04 INFO - Initialising browser for dromaeo_css test... 07:09:04 INFO - TEST-INFO | started process 2865 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpi06vwV/profile http://localhost:60710/getInfo.html) 07:09:06 INFO - PROCESS | 2865 | __metrics Screen width/height:1600/1200 07:09:06 INFO - PROCESS | 2865 | colorDepth:24 07:09:06 INFO - PROCESS | 2865 | Browser inner width/height: 1024/689 07:09:06 INFO - PROCESS | 2865 | __metrics 07:09:06 INFO - PROCESS | 2865 | 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" 07:09:06 ERROR - PROCESS | 2865 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:09:07 INFO - TEST-INFO | 2865: exit 0 07:09:07 INFO - Browser initialized. 07:09:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:09:07 INFO - TEST-INFO | started process 2867 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpi06vwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:09:19 INFO - PROCESS | 2867 | RSS: Main: 239849472 07:09:19 INFO - PROCESS | 2867 | 07:10:10 INFO - PROCESS | 2867 | 1463407810973 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] 07:12:21 INFO - PROCESS | 2867 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60710/tests/dromaeo/cssquery-ext.html) 07:12:23 INFO - PROCESS | 2867 | RSS: Main: 347381760 07:12:23 INFO - PROCESS | 2867 | 07:15:26 INFO - PROCESS | 2867 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-ext.html (next: http://localhost:60710/tests/dromaeo/cssquery-jquery.html) 07:15:26 INFO - PROCESS | 2867 | RSS: Main: 377946112 07:15:26 INFO - PROCESS | 2867 | 07:18:28 INFO - PROCESS | 2867 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60710/tests/dromaeo/cssquery-mootools.html) 07:18:29 INFO - PROCESS | 2867 | RSS: Main: 372813824 07:18:29 INFO - PROCESS | 2867 | 07:21:31 INFO - PROCESS | 2867 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60710/tests/dromaeo/cssquery-prototype.html) 07:21:32 INFO - PROCESS | 2867 | RSS: Main: 369786880 07:21:32 INFO - PROCESS | 2867 | 07:24:35 INFO - PROCESS | 2867 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60710/tests/dromaeo/cssquery-yui.html) 07:24:36 INFO - PROCESS | 2867 | RSS: Main: 339861504 07:24:36 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | Cycle 1(1): loaded http://localhost:60710/tests/dromaeo/cssquery-yui.html (next: http://localhost:60710/tests/dromaeo/cssquery-dojo.html) 07:27:39 INFO - PROCESS | 2867 | RSS: Main: 377430016 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | __start_tp_report 07:27:39 INFO - PROCESS | 2867 | _x_x_mozilla_page_load 07:27:39 INFO - PROCESS | 2867 | _x_x_mozilla_page_load_details 07:27:39 INFO - PROCESS | 2867 | |i|pagename|runs| 07:27:39 INFO - PROCESS | 2867 | |0;dojo.html;2920.7955443118976;3379.577552805899;3393.9151521211984;3408.897733068006;3412.4369479035954;5573.693446860431;5594.66432014079;5595.692236926968;5678.403767604399;5696.544276457884;5592.86017849554;5679.659220446766;5682.687452190128;5684.630499017571;5697.088465845465;4519.276915693474;5065.189569668853;5079.111155547783;5122.282880396729;5154.149565321717;5089.414717307502;5120.948790512084;5205.453427390126;5218.608604354669;5223.947760522384;5058.064258112261;5092.770825312869;5096.51583099605;5109.233614957749;5195.922061169085;5180.766865491062;5217.50433708797;5221;5225.346831646044;5275.498827611371;5029.119904016782;5036.521530454601;5094.516020978038;5128.051310507568;5142.07442660321;4592.931106033412;5513.5037846594;5531.640443371168;5565.220869078333;5613;6172.22847144107;6192.071189321638;6196.194494715658;6204.7828326008375;6216.347283535209;5005.599552035828;6167.229096362955;6182.69086545671;6283.465905398001;6286.779962701329;5747.252857128588;5787.218725472049;5792.536597072224;5817.360090390053;5819.53443724501;6220.720067596969;6267.5299352548745;6281.089242059876;6283.403076707706;6333.20834895638;5857.88284234313;5901.1148327750325;6083.665398403042;6094.847628809315;6114.571979961449;6219.968900155471;6271.717772700239;6273.12176295319;6275.874482510324;6282.371762823773;6201.069839524017;6210.223722034746;6224.128621992924;6229.626222426669;6244.875102497925;5979.133025711248;5997.550183736237;6038.486728628116;6239.625622462667;6252.562320637603;4787.353707249476;4815.662903596714;4817.879553011203;4830.975845120822;4835.830745923876;4365.148795984816;4397.966477877696;4401.031773009932;4421.491528474202;4422.204003279377;5633.971830140823;5674.177244299553;5693.9430605693415;5717.199592057115;5721.256236689203;6212.223472065992;6266.655333956585;6271.7804876830005;6295;6316.557840951089;5527.309086364205;5573.303337082864;5648.378678345379;5649.915251271234;5676.432356764373;5262.842114736564;5306.893862122736;5314.1497360422145;5330.173823057432;5331.067063263913;6216.191895053614;6248.9062664060075;6252.781152659635;6266.686665666698;6281.309056003744;6102.389761023862;6112.047575716935;6253.656048917172;6259.593126446766;6267.5299352549655;6208.658523781235;6241.843953901189;6263.467605253422;6307.747690092345;6335.968320158369;6163.660998645118;6173.722182501709;6178.41305076017;6183.96908015457;6189.783357582463;6106.915739268716;6131.662758548323;6243.8126856194385;6245.937540624535;6285.811425657238;5811.76752929895;5930.673812940157;5987.43119403656;6018.969905150446;6053.848653783691;6025.517958563217;6042.607230530001;6055.969720151371;6055.969720151371;6075.544334174866;5786.971065144648;5846;5854.443827836349;5897.144913987534;5906.497947674496;6092.539120253178;6111.74481099421;6119.624418755002;6127.853107062832;6133.05924499315;5946.881062378641;5955.940440595712;5965.850853728692;5981.312149102909;6083.452489275986 07:27:39 INFO - PROCESS | 2867 | |1;ext.html;510.7829172601643;534.2067030459766;548.9890202195953;556.7522452508633;560.2884336892149;30975.535366969485;32607.1848203795;32696.509552356703;32708.855190068352;32881.506777398325;33398.66601333986;44809.77595112028;45911.770441147746;45915.540844591545;48097.51902480974;9664.420134791917;9814;9816.950915245414;9832.31173817833;9845.55694993727;8685.95657021714;9704.563294651758;9792.55933482995;9806.803863922752;9815.754606134868;8723.869141962876;11727.824082638766;11927.165098443113;11952.163348565604;12046.518139274462;11815.113866460008;11874.228175168586;11881.702957426047;11892.048636109133;11914.225575337576;8599;11534.134939879543;11618.244814087142;11699.473523691455;11820;41621.375679364835;41896.79051604722;42066.78966605178;42133;42138;2842.957355639667;3553.8933831984823;3657.122290650225;3675.72432067596;4112.218678451085;28307.858460707565;28324.716752832413;29686.70313296861;29869.40261194764;29908.55137172944;2741.4105967217142;2766.833989960609;2790.539560972437;2836.773058155343;2892.2769307673084;545557;554752;556094;556329;557858.9999999959;53892.65268368322;60241;67154.66422667855;67156;67186.66406667935;58746;69677.3032269681;70122.29877701259;70212.29787702058;70362.24094397681;14857.925710371595;17686.823131768775;17720.468385948443;17724.55688607795;17728.29086836538;13444.46222151103;13513.256770877697;13524.729505409836;13540.796888046687;13553.457861685421;14735.63160920964;18124.006359300467;19903.900480497505;20177.596448070955;20381.082851271727;6931.930680693229;8147.75556733299;8242.711505097293;8247.628856701464;8266.297364723945;892.9240265480081;1052.55792567122;1084.2735367303926;1098.242212873115;1155.4338374196752;27896.721032789414;28916.566251506243;30276.55022096044;30905.84547077295;30920;1066.221658189526;1141.1213365708359;1189.5182451107316;1240.9193402428814;1256.309030033478;15631.296591653403;15650.9217453912;15848.832313826402;15919.602009949613;15948.840511594735;713594;717380;720588;723576;726773;63518.68240658767;63844.36155638477;63873.680631597475;63950.680246598466;64195.358046419875;1349.7165595225113;1403.052939265971;1459.5986103821533;1502.9549113526612;1520.0879472316522;29915.800395059618;31057.0682879514;31082.378352433276;31654.525182121826;32234.516482253253;53197.670058248856;53308;53762.731186343815;53802.73098634482;53870;558456;559355;560251;561907;564869;23907.282781516584;24752.25743227706;25251.242462726146;27686;28112.578311324938;46468.30297545607;48239;48246;48710.75644621754;48949.5105048945;39579.80210098931;39757.80121099376;40280.395794062526;40949.59050409458;41351.96620084522;37782.81108594439;37941.430878536295;39694.60305396909;40953.97615059647;41771.16457670891;37578;38996.805015974736;39124.80437597794;39834.80082599684;40655.59344406518;33437;34089.147771305914;34194.48708269328;34249;34630.480542792386;25178.392946828324;25266.242012739647;25375.746242537338;25536.61695074538;25942.481150377265 07:27:39 INFO - PROCESS | 2867 | |2;jquery.html;398.2532751091703;404.5893418180546;407.338075627106;413.1221155045528;426.8804734674292;20680.379588612348;21001.57996840064;21322.57354852902;21510.784892151114;22102.557948841015;20052.39842804718;21144.365669029914;21403.571928561458;21808;21929.890350548227;6086.087086936951;6149.569530132892;6163.291221509517;6205.565610407273;6226.501879849601;5820.923129221108;6106.389361063881;6113.908291375633;6125.938740612603;6142.692865356737;6079.817605471843;6110.9916863717435;6115.327313995457;6115.357887421824;6136.631802091866;5925.4370834770625;6033.487153591928;6082.756689732405;6093.360197179321;6107.877842443148;4509.864704058883;5149.098907691139;5322.0686379883755;5331.813386531491;5336.06618841705;26917.461650767073;26935.922563097647;27125.72874271252;27459;27462.862685686643;2098.643230650793;2121.893905304744;2135.8718476891436;2175.4670105824016;2813.1138691312376;14850.628734281621;14911.328990195467;15378.846211537853;15722.056676599326;15781.921090394475;1696.6681155293159;1756.323815331094;1776.5025792778042;1793.7757780277466;1816.8637352198505;116688;122081;124537.75462245443;125247.37376313152;125329.37335313356;10907.509162087566;11117.110631149488;11393.544258229744;11413.828792568118;11529.48117334722;11866.34735089561;14264.35959864608;14359;14379.928100359431;14393.78409323861;8629.180227878342;8859.867101993475;8872.689455869013;8983.46099234048;9001.099890011077;8854.64581416749;8857.867131993025;8857.867131993025;8976.910230897738;9014.323925705467;6772.525923185428;6905.447564194852;6917.550359226634;6927.376536111774;6939;1722.6813039587466;1845.7415961765364;1890.423420856652;1900.4013735673495;1917.1756144857845;747.4506237915128;789.455275859655;816.3102178659024;1053.257453495284;1054.878688950765;21570.460738481663;22338.553228935656;22431.887840560692;22489.987950542265;22666.546669066527;801.6633014134078;1039.1946241662772;1046.1108058150662;1079.4440862955585;1092.7049696581878;11308.830367544493;11660.242084264495;11673.768704786376;11816.46825892837;11829.349385783864;516997.3951693128;575999;578062;589874;602353;132725.33637331752;133670.33164834307;134518;145536;157130.0726430987;918.4030380252756;932.2122806228726;1026.1688032693542;1046.4663021858908;1078.5524007536785;19101.331453399063;19271.13279902436;19300.613987719888;19310.517237069187;19399.612007760046;38576;54801;54806.90386192333;55850.72074639601;56775.43224567702;73601;76819;81101.59449202716;81470.37059258664;82130.17869821226;5389.676619402849;7744.961275193588;7770.45606807518;7978.3218426434405;8662.523561204194;11633.825492617449;12104.09219308573;12686.682832929251;12717.682057948625;13375.598732038054;11305.321680699184;11361.943190283995;11394.550171713121;11502.769944601228;11660.41697915084;11162.9953304203;11195.664130075786;11478.417356095146;11480.425978701198;11616.477258523219;11311.321320720783;11455.484503197542;11715;11829.17195796286;11948.223365481215;7583.165851756192;7640.159582445815;7702.691892324468;7818.100918394457;7960.601969901598;10966.232363734782;11181.608643697431;11285.43572821372;11306.095512358826;11526.308421494738 07:27:39 INFO - PROCESS | 2867 | |3;mootools.html;1432.9897422317267;1587.380921440638;1593.442295196681;1825.4432398118577;1952.1215453046125;8079.87880181797;8891.288696904248;9129.406588571755;9164.037776033512;9274.258059355252;8847.867281990759;9059.365844390895;9073.546322683856;9086;9086.182243598076;2412.3245491262473;2457.6682147910074;2458.9754102459015;2461.212412028152;2463.6550882876413;2086.9252335047463;2379.7501262367464;2408.229366602679;2444.963325550118;2455.17751553229;2414.1791790791126;2424.83026188166;2444.8533088014774;2447.6940382452194;2449.730529641738;2379.916702915407;2402.1592442645024;2406.843555168917;2407.987960060206;2426.2721183645012;2397.4126339047043;2416.1905761569897;2420.8063354931564;2422.7819496245247;2422.903083876643;8786.209241168262;8948.373613847034;9040.095990400972;9051.818963620757;9063.229625481841;4602.516735742733;5617.101263797814;5623.072193088176;5637.71811409432;5639.71801409932;3516.578010638714;3614.3855544557487;3660.1764602964386;3680.7423480356388;3685.63143685632;1048.6906362622967;1065.9946700266448;1068.4230515521608;1070.7590792071721;1073.6188653028141;12241.449134788956;12362;12380.133390662657;12381.814272785825;12382.380880955916;4092.2633925893624;4640.11837750833;4766.833160839354;4808.927866082012;4813.470518242991;9546.761330966643;9560.521973901277;9567.473788941676;9567.952160239154;9569.904300957041;4583.358329833826;5391.56867450603;5394.703291319015;5398.217258497496;5398.379186393537;4502.144592527409;4584.404027476407;4721;4980.526849949249;5059.72171530562;3821.8662346817728;3845.769253844778;3852.460655508231;3887.436321733333;3996.0009997500624;1081.561967403203;1232.6610182199968;1239.783037968352;1247.2703468470866;1248.4569212392457;919.9310051746146;926.4070994563485;935.6163972771132;937.3110763588757;1007.8941711120374;5079.517445842639;5555.833325000256;5947.078202878561;6089.939100608937;6170.50635949123;440.80163926233325;457.1017949728732;458.6949678463851;458.86463493269565;477.164961317694;3601.4597810328137;3677.8344974476213;3864.7294689371474;3933.8426462941734;3985;10772.407517586607;11029.22795404314;11274.492647830868;11285.492152852978;11315.094792416587;9474.621015159455;11209.607663731731;11212;11218.439078046065;11227.382493962747;8209.548474833942;8469.407141500036;8481.575921203916;8563.015253245832;8565.828683426296;5912.556558258234;5984.371640977809;6043.879122417439;6043.909341359971;6053.939460605514;9889.901100988898;10251.538680759235;10279.434631095362;10429.634962776267;10434.530446129791;10118.392896425943;10371.94814025925;10538.104261137889;10615.363078215332;10669.626563070255;7681;7750.92249077502;7817.452778305464;7846.5292082475235;7878.290953813955;8487.575621219037;8522.147785221676;8539;8567.100454452193;8641.222289993932;7569.243075692387;7728.729494467667;7777.883331749915;7865.174156713462;7877.487963282369;7601.923980760343;7623.923760762544;7651.770446886602;7800.531968081933;7825.843483130419;7164.641767911688;7270.9636451817405;7348.853022939405;7363.410927125924;7382.151052629017;4447.799849006868;4956.95903860179;4988.8752781180765;5024.773885175103;5063.924041139312;4993.975030124826;5021.648484606042;5028.597712182944;5092.134337162617;5242.18746094356 07:27:39 INFO - PROCESS | 2867 | |4;prototype.html;313.5751057317335;322.4614893128475;331.62029476249694;334.7823914455602;337.45501015859395;4755.405574303212;5273.8924825786635;5411.026015317247;5441.83674489765;5478.01395748765;5396.838094857151;5417.918731219035;5423.83728488145;5462.781488740456;5480.780768769245;3261.5107733839936;3632.7648599476174;3698.6486283802997;4075.572064933184;4136.31750761124;4017.1965606878593;4070.5725898780647;4071.7581137752973;4091.713580049398;4115.732477388975;3702.481652568629;3735.1969326594776;4057.4116753070934;4071.109512246309;4089.5705950875176;3350.977951724723;3601.927961440783;3887.863924762634;4045.3211917054387;4100.446439730628;2699.460107978412;3087.8729263818545;3283.96716032839;3541.140901026956;3571.910702232465;4239.1521695660995;4529.88123420536;4750.881227969328;4778.566430071;5195.5324020837925;4365.585269399402;4553.567411095941;4722.505346043903;4731.195696731564;4785.784639691222;5450.972745136289;5463.644863083927;5671.958095085766;5689.216670913837;5692.23154874095;3970.7816070116132;3973.284808734427;3988.541317748467;3995.4206640037323;4020.336644453662;4762.618990480753;5464.808731694372;5916.497097746654;5952.839196356669;6054.485368743662;3412.19813343864;3566.625504322061;4030.8992275193355;4033.2740106780475;4042.312806822847;4115.506139263248;4119.608637179464;4126.030382860025;4134.607212314825;4134.937975930364;4153.958460415418;4672.719636821802;4708.552687494683;4738.360321356573;4746.667733258639;4659.9767001165455;5743.396943320975;5755.3381361143165;5771.451712087345;5797.826065218051;1330.813686083949;1354.5462270139615;1357.253510569183;1372.30012693527;1372.997711670486;802.1937952357843;824.1593574553922;845.5118990576507;847.8685803700437;854.5515351479307;4416.646668266595;4842.346283251786;4846.61227101831;5288.699415754159;5288.867778305573;5843.386444423274;5865.853353666193;5881.970655135334;5894.320118766131;5895.4399332063385;4555.63554915606;5089.033083714083;5181.145111056709;5323.281357016753;5323.30796996388;2903.4918889194305;3063.9387212256074;3096.7058129477664;3097.822827325602;3116.532520122;5169.510580358243;5606.887862242732;5638.393057978456;5674.2623458950075;5913.05391137416;5220.451852555503;5242.7378631068295;5446.494612478305;5600.047991841397;5829;5869.647821130746;6015.4586087252355;6114.296855861633;6129.938700612936;6143.1706719592285;4850.442199147144;5067.949320506896;5679.148127780866;5905.435059709094;5917.911231331447;5138.586888605514;5220.963824108525;5431.701256430934;5756.683382414008;5869.589128760946;4975.676581022222;5477.260569823023;6149.477294430024;6152.231495826985;6153.815385538441;3467.9076091031243;3649.9817500912327;3852.8073596320633;3945.0669172106036;3967.70242231828;5436.320459942507;5866.472017518444;5886.705664716661;5920.940790592039;5941.910871336847;5719.828405147852;5758.782868595537;5776.480116789509;5814.04068328738;5818.352649544362;5037;5477.911726512358;5717.7141142941855;5736.282964629421;5741.25363702716;4889.410941443974;4973.303737476828;5390.730463476889;5620.241267429008;5666.781641947001;5504.339479262514;5543.307086614173;5735;5741;5756.395578464201;5222.42553319127;5326.547243484347;5555.055640541199;5643.689597072036;5657.444202844086 07:27:39 INFO - PROCESS | 2867 | |5;yui.html;571.6684323092609;707.1867352544576;730.9195988441271;761.5392687424109;781.6091954022988;15122.546323610295;15465.536033918972;17063.573410664754;17844.643107137883;17864.10679466025;16682.58293542662;16766.32934682611;16809.24358403872;16809.915950420233;16865.241064152113;757.3751654884715;782.3545574900729;900.522722956834;943.8206740719276;953.7615596100975;939.8496240601505;946.4415994563207;946.607158029417;948.7438391634255;951.2675240065146;918.7611221082533;986.058314309836;991.7818080022383;992.3450522655049;993.8161440133598;792.8414317136596;962.7833737409097;981.6024510073431;995.3381001633904;998.7902540466511;736.5507040705219;738.6233021159247;738.9113306403266;740.2190688823289;742.8105833012547;15335.463258785889;15375.538733838002;15379.384824607003;15382.230888455644;15391.384344626202;12073.57742479009;12173.756524869454;12184.756304873852;12202;12473.93763031188;10776.19178561611;10905.236633435663;11059.668209953714;11091.83362249567;11459;3936.6063393660406;3993.8602148924647;4062.1672557125717;4098.364753463218;4315.956840431619;86925.56537217273;87136;87247;88688;88766.55616721875;9195.90804091964;9236.5843537041;9477.28920330978;9511.476868772146;9817.361871478332;20844.1662333508;20874.95625218733;20902.954852257328;20912.68630970537;20924.895375523025;7247.456440766963;7274.527155734861;7436.851262974818;7611.467197296136;7630;3425.7088147507743;3906.4726261954843;3953.6639385651965;3978.622030907059;3997.6801855851463;3305.966940330614;3410.897673069812;3411.3006833599547;3413.4879768034994;3414.5219669246326;648.1476857931805;657.4346062386344;660.7753363856312;675.2336098528206;711.7081996381562;537.5242910024656;592.7747455966705;603.0682595390133;607.3868965209228;613.3927411862313;8194.50832950013;8214.794630134174;8241.134680858424;8309.542975136304;8355.498670079814;520.8828013696888;555.8443635781988;577.7746678795273;582.3274118393263;604.8246008657528;3848.230353929225;3917.118648304109;3939.1136994176654;3974.4634474345594;4164.8334066637;69975;91655.54172229096;92189.5390523043;93619.65950851147;98441;78624;80513.59743201247;82183.5890820554;83515;84094.15905840986;73234.9014764779;73865;74933.62533187299;75077.62461187658;75297.11757206114;6957.686904089227;7535.73624923125;7622.504537205064;7696.769096927102;7776.300132988058;40304.3954340691;41384.79307603563;41555.545555905395;42032.78983605062;42226;39666;44287.33568996532;55369.44630553643;55438.891222176135;57371.713141434026;9307.627694892128;10357.326773759682;10771.084457821173;11288.871112888646;11888.405579721153;12167.634970950885;12180.390980451119;12291.631251062483;12300.753984920431;12305.876941230832;11638.6508404748;11824.290542567473;11824.527018919147;12102.818457722966;12198;11488;11759.059275257789;11980.820287695517;12049.89126902806;12480.75038499243;11507.079433645122;11515.827262591236;11606.187566870238;11809;12440.68898277514;9603;9611.951940240255;9914.454704991294;9918.40489570628;10248.38509689421;6990.405815505739;9345.158935695548;9501.572429240563;9682.515874206121;9960.483786362556 07:27:39 INFO - PROCESS | 2867 | __end_tp_report 07:27:39 INFO - PROCESS | 2867 | __start_cc_report 07:27:39 INFO - PROCESS | 2867 | _x_x_mozilla_cycle_collect,1110 07:27:39 INFO - PROCESS | 2867 | __end_cc_report 07:27:39 INFO - PROCESS | 2867 | __startTimestamp1463408859472__endTimestamp 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | ------- Summary: start ------- 07:27:39 INFO - PROCESS | 2867 | Number of tests: 6 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | [#0] dojo.html Cycles:165 Average:5684.05 Median:5901.11 stddev:663.89 (11.3%) stddev-sans-first:629.54 07:27:39 INFO - PROCESS | 2867 | Values: 2920.8 3379.6 3393.9 3408.9 3412.4 5573.7 5594.7 5595.7 5678.4 5696.5 5592.9 5679.7 5682.7 5684.6 5697.1 4519.3 5065.2 5079.1 5122.3 5154.1 5089.4 5120.9 5205.5 5218.6 5223.9 5058.1 5092.8 5096.5 5109.2 5195.9 5180.8 5217.5 5221.0 5225.3 5275.5 5029.1 5036.5 5094.5 5128.1 5142.1 4592.9 5513.5 5531.6 5565.2 5613.0 6172.2 6192.1 6196.2 6204.8 6216.3 5005.6 6167.2 6182.7 6283.5 6286.8 5747.3 5787.2 5792.5 5817.4 5819.5 6220.7 6267.5 6281.1 6283.4 6333.2 5857.9 5901.1 6083.7 6094.8 6114.6 6220.0 6271.7 6273.1 6275.9 6282.4 6201.1 6210.2 6224.1 6229.6 6244.9 5979.1 5997.6 6038.5 6239.6 6252.6 4787.4 4815.7 4817.9 4831.0 4835.8 4365.1 4398.0 4401.0 4421.5 4422.2 5634.0 5674.2 5693.9 5717.2 5721.3 6212.2 6266.7 6271.8 6295.0 6316.6 5527.3 5573.3 5648.4 5649.9 5676.4 5262.8 5306.9 5314.1 5330.2 5331.1 6216.2 6248.9 6252.8 6266.7 6281.3 6102.4 6112.0 6253.7 6259.6 6267.5 6208.7 6241.8 6263.5 6307.7 6336.0 6163.7 6173.7 6178.4 6184.0 6189.8 6106.9 6131.7 6243.8 6245.9 6285.8 5811.8 5930.7 5987.4 6019.0 6053.8 6025.5 6042.6 6056.0 6056.0 6075.5 5787.0 5846.0 5854.4 5897.1 5906.5 6092.5 6111.7 6119.6 6127.9 6133.1 5946.9 5955.9 5965.9 5981.3 6083.5 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | [#1] ext.html Cycles:180 Average:74505.32 Median:3666.42 stddev:165049.01 (4501.6%) stddev-sans-first:165418.51 07:27:39 INFO - PROCESS | 2867 | Values: 510.8 534.2 549.0 556.8 560.3 30975.5 32607.2 32696.5 32708.9 32881.5 33398.7 44809.8 45911.8 45915.5 48097.5 9664.4 9814.0 9817.0 9832.3 9845.6 8686.0 9704.6 9792.6 9806.8 9815.8 8723.9 11727.8 11927.2 11952.2 12046.5 11815.1 11874.2 11881.7 11892.0 11914.2 8599.0 11534.1 11618.2 11699.5 11820.0 41621.4 41896.8 42066.8 42133.0 42138.0 2843.0 3553.9 3657.1 3675.7 4112.2 28307.9 28324.7 29686.7 29869.4 29908.6 2741.4 2766.8 2790.5 2836.8 2892.3 545557.0 554752.0 556094.0 556329.0 557859.0 53892.7 60241.0 67154.7 67156.0 67186.7 58746.0 69677.3 70122.3 70212.3 70362.2 14857.9 17686.8 17720.5 17724.6 17728.3 13444.5 13513.3 13524.7 13540.8 13553.5 14735.6 18124.0 19903.9 20177.6 20381.1 6931.9 8147.8 8242.7 8247.6 8266.3 892.9 1052.6 1084.3 1098.2 1155.4 27896.7 28916.6 30276.6 30905.8 30920.0 1066.2 1141.1 1189.5 1240.9 1256.3 15631.3 15650.9 15848.8 15919.6 15948.8 713594.0 717380.0 720588.0 723576.0 726773.0 63518.7 63844.4 63873.7 63950.7 64195.4 1349.7 1403.1 1459.6 1503.0 1520.1 29915.8 31057.1 31082.4 31654.5 32234.5 53197.7 53308.0 53762.7 53802.7 53870.0 558456.0 559355.0 560251.0 561907.0 564869.0 23907.3 24752.3 25251.2 27686.0 28112.6 46468.3 48239.0 48246.0 48710.8 48949.5 39579.8 39757.8 40280.4 40949.6 41352.0 37782.8 37941.4 39694.6 40954.0 41771.2 37578.0 38996.8 39124.8 39834.8 40655.6 33437.0 34089.1 34194.5 34249.0 34630.5 25178.4 25266.2 25375.7 25536.6 25942.5 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | [#2] jquery.html Cycles:180 Average:35213.47 Median:11991.73 stddev:96381.08 (803.7%) stddev-sans-first:96616.01 07:27:39 INFO - PROCESS | 2867 | Values: 398.3 404.6 407.3 413.1 426.9 20680.4 21001.6 21322.6 21510.8 22102.6 20052.4 21144.4 21403.6 21808.0 21929.9 6086.1 6149.6 6163.3 6205.6 6226.5 5820.9 6106.4 6113.9 6125.9 6142.7 6079.8 6111.0 6115.3 6115.4 6136.6 5925.4 6033.5 6082.8 6093.4 6107.9 4509.9 5149.1 5322.1 5331.8 5336.1 26917.5 26935.9 27125.7 27459.0 27462.9 2098.6 2121.9 2135.9 2175.5 2813.1 14850.6 14911.3 15378.8 15722.1 15781.9 1696.7 1756.3 1776.5 1793.8 1816.9 116688.0 122081.0 124537.8 125247.4 125329.4 10907.5 11117.1 11393.5 11413.8 11529.5 11866.3 14264.4 14359.0 14379.9 14393.8 8629.2 8859.9 8872.7 8983.5 9001.1 8854.6 8857.9 8857.9 8976.9 9014.3 6772.5 6905.4 6917.6 6927.4 6939.0 1722.7 1845.7 1890.4 1900.4 1917.2 747.5 789.5 816.3 1053.3 1054.9 21570.5 22338.6 22431.9 22490.0 22666.5 801.7 1039.2 1046.1 1079.4 1092.7 11308.8 11660.2 11673.8 11816.5 11829.3 516997.4 575999.0 578062.0 589874.0 602353.0 132725.3 133670.3 134518.0 145536.0 157130.1 918.4 932.2 1026.2 1046.5 1078.6 19101.3 19271.1 19300.6 19310.5 19399.6 38576.0 54801.0 54806.9 55850.7 56775.4 73601.0 76819.0 81101.6 81470.4 82130.2 5389.7 7745.0 7770.5 7978.3 8662.5 11633.8 12104.1 12686.7 12717.7 13375.6 11305.3 11361.9 11394.6 11502.8 11660.4 11163.0 11195.7 11478.4 11480.4 11616.5 11311.3 11455.5 11715.0 11829.2 11948.2 7583.2 7640.2 7702.7 7818.1 7960.6 10966.2 11181.6 11285.4 11306.1 11526.3 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | [#3] mootools.html Cycles:180 Average:5824.20 Median:4743.92 stddev:3340.53 (70.4%) stddev-sans-first:3333.60 07:27:39 INFO - PROCESS | 2867 | Values: 1433.0 1587.4 1593.4 1825.4 1952.1 8079.9 8891.3 9129.4 9164.0 9274.3 8847.9 9059.4 9073.5 9086.0 9086.2 2412.3 2457.7 2459.0 2461.2 2463.7 2086.9 2379.8 2408.2 2445.0 2455.2 2414.2 2424.8 2444.9 2447.7 2449.7 2379.9 2402.2 2406.8 2408.0 2426.3 2397.4 2416.2 2420.8 2422.8 2422.9 8786.2 8948.4 9040.1 9051.8 9063.2 4602.5 5617.1 5623.1 5637.7 5639.7 3516.6 3614.4 3660.2 3680.7 3685.6 1048.7 1066.0 1068.4 1070.8 1073.6 12241.4 12362.0 12380.1 12381.8 12382.4 4092.3 4640.1 4766.8 4808.9 4813.5 9546.8 9560.5 9567.5 9568.0 9569.9 4583.4 5391.6 5394.7 5398.2 5398.4 4502.1 4584.4 4721.0 4980.5 5059.7 3821.9 3845.8 3852.5 3887.4 3996.0 1081.6 1232.7 1239.8 1247.3 1248.5 919.9 926.4 935.6 937.3 1007.9 5079.5 5555.8 5947.1 6089.9 6170.5 440.8 457.1 458.7 458.9 477.2 3601.5 3677.8 3864.7 3933.8 3985.0 10772.4 11029.2 11274.5 11285.5 11315.1 9474.6 11209.6 11212.0 11218.4 11227.4 8209.5 8469.4 8481.6 8563.0 8565.8 5912.6 5984.4 6043.9 6043.9 6053.9 9889.9 10251.5 10279.4 10429.6 10434.5 10118.4 10371.9 10538.1 10615.4 10669.6 7681.0 7750.9 7817.5 7846.5 7878.3 8487.6 8522.1 8539.0 8567.1 8641.2 7569.2 7728.7 7777.9 7865.2 7877.5 7601.9 7623.9 7651.8 7800.5 7825.8 7164.6 7271.0 7348.9 7363.4 7382.2 4447.8 4957.0 4988.9 5024.8 5063.9 4994.0 5021.6 5028.6 5092.1 5242.2 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | [#4] prototype.html Cycles:180 Average:4579.23 Median:5188.34 stddev:1421.83 (27.4%) stddev-sans-first:1389.30 07:27:39 INFO - PROCESS | 2867 | Values: 313.6 322.5 331.6 334.8 337.5 4755.4 5273.9 5411.0 5441.8 5478.0 5396.8 5417.9 5423.8 5462.8 5480.8 3261.5 3632.8 3698.6 4075.6 4136.3 4017.2 4070.6 4071.8 4091.7 4115.7 3702.5 3735.2 4057.4 4071.1 4089.6 3351.0 3601.9 3887.9 4045.3 4100.4 2699.5 3087.9 3284.0 3541.1 3571.9 4239.2 4529.9 4750.9 4778.6 5195.5 4365.6 4553.6 4722.5 4731.2 4785.8 5451.0 5463.6 5672.0 5689.2 5692.2 3970.8 3973.3 3988.5 3995.4 4020.3 4762.6 5464.8 5916.5 5952.8 6054.5 3412.2 3566.6 4030.9 4033.3 4042.3 4115.5 4119.6 4126.0 4134.6 4134.9 4154.0 4672.7 4708.6 4738.4 4746.7 4660.0 5743.4 5755.3 5771.5 5797.8 1330.8 1354.5 1357.3 1372.3 1373.0 802.2 824.2 845.5 847.9 854.6 4416.6 4842.3 4846.6 5288.7 5288.9 5843.4 5865.9 5882.0 5894.3 5895.4 4555.6 5089.0 5181.1 5323.3 5323.3 2903.5 3063.9 3096.7 3097.8 3116.5 5169.5 5606.9 5638.4 5674.3 5913.1 5220.5 5242.7 5446.5 5600.0 5829.0 5869.6 6015.5 6114.3 6129.9 6143.2 4850.4 5067.9 5679.1 5905.4 5917.9 5138.6 5221.0 5431.7 5756.7 5869.6 4975.7 5477.3 6149.5 6152.2 6153.8 3467.9 3650.0 3852.8 3945.1 3967.7 5436.3 5866.5 5886.7 5920.9 5941.9 5719.8 5758.8 5776.5 5814.0 5818.4 5037.0 5477.9 5717.7 5736.3 5741.3 4889.4 4973.3 5390.7 5620.2 5666.8 5504.3 5543.3 5735.0 5741.0 5756.4 5222.4 5326.5 5555.1 5643.7 5657.4 07:27:39 INFO - PROCESS | 2867 | 07:27:39 INFO - PROCESS | 2867 | [#5] yui.html Cycles:180 Average:18074.41 Median:587.55 stddev:25488.68 (4338.1%) stddev-sans-first:25526.30 07:27:39 INFO - PROCESS | 2867 | Values: 571.7 707.2 730.9 761.5 781.6 15122.5 15465.5 17063.6 17844.6 17864.1 16682.6 16766.3 16809.2 16809.9 16865.2 757.4 782.4 900.5 943.8 953.8 939.8 946.4 946.6 948.7 951.3 918.8 986.1 991.8 992.3 993.8 792.8 962.8 981.6 995.3 998.8 736.6 738.6 738.9 740.2 742.8 15335.5 15375.5 15379.4 15382.2 15391.4 12073.6 12173.8 12184.8 12202.0 12473.9 10776.2 10905.2 11059.7 11091.8 11459.0 3936.6 3993.9 4062.2 4098.4 4316.0 86925.6 87136.0 87247.0 88688.0 88766.6 9195.9 9236.6 9477.3 9511.5 9817.4 20844.2 20875.0 20903.0 20912.7 20924.9 7247.5 7274.5 7436.9 7611.5 7630.0 3425.7 3906.5 3953.7 3978.6 3997.7 3306.0 3410.9 3411.3 3413.5 3414.5 648.1 657.4 660.8 675.2 711.7 537.5 592.8 603.1 607.4 613.4 8194.5 8214.8 8241.1 8309.5 8355.5 520.9 555.8 577.8 582.3 604.8 3848.2 3917.1 3939.1 3974.5 4164.8 69975.0 91655.5 92189.5 93619.7 98441.0 78624.0 80513.6 82183.6 83515.0 84094.2 73234.9 73865.0 74933.6 75077.6 75297.1 6957.7 7535.7 7622.5 7696.8 7776.3 40304.4 41384.8 41555.5 42032.8 42226.0 39666.0 44287.3 55369.4 55438.9 57371.7 9307.6 10357.3 10771.1 11288.9 11888.4 12167.6 12180.4 12291.6 12300.8 12305.9 11638.7 11824.3 11824.5 12102.8 12198.0 11488.0 11759.1 11980.8 12049.9 12480.8 11507.1 11515.8 11606.2 11809.0 12440.7 9603.0 9612.0 9914.5 9918.4 10248.4 6990.4 9345.2 9501.6 9682.5 9960.5 07:27:39 INFO - PROCESS | 2867 | -------- Summary: end -------- 07:27:39 INFO - PROCESS | 2867 | 07:27:40 INFO - TEST-INFO | 2867: exit 0 07:27:40 INFO - TEST-OK | dromaeo_css | took 1115722ms 07:27:40 INFO - TEST-START | kraken 07:27:40 INFO - operating with platform_type : mac_ 07:27:40 INFO - Initialising browser for kraken test... 07:27:40 INFO - TEST-INFO | started process 2870 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmp4CHcCs/profile http://localhost:60710/getInfo.html) 07:27:42 INFO - PROCESS | 2870 | __metrics Screen width/height:1600/1200 07:27:42 INFO - PROCESS | 2870 | colorDepth:24 07:27:42 INFO - PROCESS | 2870 | Browser inner width/height: 1024/689 07:27:42 INFO - PROCESS | 2870 | __metrics 07:27:42 INFO - PROCESS | 2870 | 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" 07:27:42 ERROR - PROCESS | 2870 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:27:43 INFO - TEST-INFO | 2870: exit 0 07:27:43 INFO - Browser initialized. 07:27:43 INFO - Running cycle 1/1 for kraken test... 07:27:43 INFO - TEST-INFO | started process 2872 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmp4CHcCs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:27:54 INFO - PROCESS | 2872 | RSS: Main: 239714304 07:27:54 INFO - PROCESS | 2872 | 07:27:57 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-beat-detection) 07:27:58 INFO - PROCESS | 2872 | RSS: Main: 289263616 07:27:58 INFO - PROCESS | 2872 | 07:28:01 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-dft) 07:28:02 INFO - PROCESS | 2872 | RSS: Main: 298520576 07:28:02 INFO - PROCESS | 2872 | 07:28:05 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-fft) 07:28:06 INFO - PROCESS | 2872 | RSS: Main: 384335872 07:28:06 INFO - PROCESS | 2872 | 07:28:08 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60710/tests/kraken/driver.html?testName=audio-oscillator) 07:28:08 INFO - PROCESS | 2872 | RSS: Main: 284164096 07:28:08 INFO - PROCESS | 2872 | 07:28:11 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60710/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:28:11 INFO - PROCESS | 2872 | RSS: Main: 288215040 07:28:11 INFO - PROCESS | 2872 | 07:28:14 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60710/tests/kraken/driver.html?testName=imaging-darkroom) 07:28:15 INFO - PROCESS | 2872 | RSS: Main: 324214784 07:28:15 INFO - PROCESS | 2872 | 07:28:18 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60710/tests/kraken/driver.html?testName=imaging-desaturate) 07:28:19 INFO - PROCESS | 2872 | RSS: Main: 326062080 07:28:19 INFO - PROCESS | 2872 | 07:28:22 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60710/tests/kraken/driver.html?testName=json-parse-financial) 07:28:22 INFO - PROCESS | 2872 | RSS: Main: 326791168 07:28:22 INFO - PROCESS | 2872 | 07:28:24 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60710/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:28:25 INFO - PROCESS | 2872 | RSS: Main: 316833792 07:28:25 INFO - PROCESS | 2872 | 07:28:27 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:28:27 INFO - PROCESS | 2872 | RSS: Main: 324157440 07:28:27 INFO - PROCESS | 2872 | 07:28:29 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:28:30 INFO - PROCESS | 2872 | RSS: Main: 325382144 07:28:30 INFO - PROCESS | 2872 | 07:28:33 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:28:34 INFO - PROCESS | 2872 | RSS: Main: 330121216 07:28:34 INFO - PROCESS | 2872 | 07:28:37 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:28:37 INFO - PROCESS | 2872 | RSS: Main: 327827456 07:28:37 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:60710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60710/tests/kraken/driver.html?testName=ai-astar) 07:28:39 INFO - PROCESS | 2872 | RSS: Main: 374145024 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | __start_tp_report 07:28:39 INFO - PROCESS | 2872 | _x_x_mozilla_page_load 07:28:39 INFO - PROCESS | 2872 | _x_x_mozilla_page_load_details 07:28:39 INFO - PROCESS | 2872 | |i|pagename|runs| 07:28:39 INFO - PROCESS | 2872 | |0;ai-astar;111;103;110;111;114;161;163;109;110;109 07:28:39 INFO - PROCESS | 2872 | |1;audio-beat-detection;154;124;141;237;141;158;138;116;166;116 07:28:39 INFO - PROCESS | 2872 | |2;audio-dft;187;185;184;182;186;184;186;183;183;178 07:28:39 INFO - PROCESS | 2872 | |3;audio-fft;78;95;81;162;57;59;57;57;113;54 07:28:39 INFO - PROCESS | 2872 | |4;audio-oscillator;91;99;134;79;79;77;102;96;67;124 07:28:39 INFO - PROCESS | 2872 | |5;imaging-gaussian-blur;86;82;86;84;85;82;85;82;86;83 07:28:39 INFO - PROCESS | 2872 | |6;imaging-darkroom;92;92;92;92;92;92;93;93;92;91 07:28:39 INFO - PROCESS | 2872 | |7;imaging-desaturate;88;86;90;92;92;99;144;87;69;70 07:28:39 INFO - PROCESS | 2872 | |8;json-parse-financial;56;59;58;58;58;58;57;118;56;55 07:28:39 INFO - PROCESS | 2872 | |9;json-stringify-tinderbox;63;66;74;72;71;69;67;70;69;69 07:28:39 INFO - PROCESS | 2872 | |10;stanford-crypto-aes;85;83;85;84;84;83;84;85;85;86 07:28:39 INFO - PROCESS | 2872 | |11;stanford-crypto-ccm;126;142;152;147;130;136;135;136;215;136 07:28:39 INFO - PROCESS | 2872 | |12;stanford-crypto-pbkdf2;163;164;161;163;161;163;161;163;163;163 07:28:39 INFO - PROCESS | 2872 | |13;stanford-crypto-sha256-iterative;65;65;64;65;68;85;82;81;67;64 07:28:39 INFO - PROCESS | 2872 | __end_tp_report 07:28:39 INFO - PROCESS | 2872 | __start_cc_report 07:28:39 INFO - PROCESS | 2872 | _x_x_mozilla_cycle_collect,2296 07:28:39 INFO - PROCESS | 2872 | __end_cc_report 07:28:39 INFO - PROCESS | 2872 | __startTimestamp1463408919856__endTimestamp 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | ------- Summary: start ------- 07:28:39 INFO - PROCESS | 2872 | Number of tests: 14 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#0] ai-astar Cycles:10 Average:120.10 Median:111.00 stddev:22.26 (20.1%) stddev-sans-first:23.36 07:28:39 INFO - PROCESS | 2872 | Values: 111.0 103.0 110.0 111.0 114.0 161.0 163.0 109.0 110.0 109.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:147.50 stddev:35.26 (23.9%) stddev-sans-first:37.36 07:28:39 INFO - PROCESS | 2872 | Values: 154.0 124.0 141.0 237.0 141.0 158.0 138.0 116.0 166.0 116.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:2.57 (1.4%) stddev-sans-first:2.46 07:28:39 INFO - PROCESS | 2872 | Values: 187.0 185.0 184.0 182.0 186.0 184.0 186.0 183.0 183.0 178.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#3] audio-fft Cycles:10 Average:81.30 Median:58.00 stddev:34.53 (59.5%) stddev-sans-first:36.60 07:28:39 INFO - PROCESS | 2872 | Values: 78.0 95.0 81.0 162.0 57.0 59.0 57.0 57.0 113.0 54.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:21.25 (26.9%) stddev-sans-first:22.49 07:28:39 INFO - PROCESS | 2872 | Values: 91.0 99.0 134.0 79.0 79.0 77.0 102.0 96.0 67.0 124.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#5] imaging-gaussian-blur Cycles:10 Average:84.10 Median:85.00 stddev:1.73 (2.0%) stddev-sans-first:1.69 07:28:39 INFO - PROCESS | 2872 | Values: 86.0 82.0 86.0 84.0 85.0 82.0 85.0 82.0 86.0 83.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:28:39 INFO - PROCESS | 2872 | Values: 92.0 92.0 92.0 92.0 92.0 92.0 93.0 93.0 92.0 91.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:89.00 stddev:20.67 (23.2%) stddev-sans-first:21.88 07:28:39 INFO - PROCESS | 2872 | Values: 88.0 86.0 90.0 92.0 92.0 99.0 144.0 87.0 69.0 70.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:58.00 stddev:19.26 (33.2%) stddev-sans-first:20.25 07:28:39 INFO - PROCESS | 2872 | Values: 56.0 59.0 58.0 58.0 58.0 58.0 57.0 118.0 56.0 55.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:3.13 (4.5%) stddev-sans-first:2.45 07:28:39 INFO - PROCESS | 2872 | Values: 63.0 66.0 74.0 72.0 71.0 69.0 67.0 70.0 69.0 69.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 07:28:39 INFO - PROCESS | 2872 | Values: 85.0 83.0 85.0 84.0 84.0 83.0 84.0 85.0 85.0 86.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:139.00 stddev:25.58 (18.4%) stddev-sans-first:26.14 07:28:39 INFO - PROCESS | 2872 | Values: 126.0 142.0 152.0 147.0 130.0 136.0 135.0 136.0 215.0 136.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:163.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 07:28:39 INFO - PROCESS | 2872 | Values: 163.0 164.0 161.0 163.0 161.0 163.0 161.0 163.0 163.0 163.0 07:28:39 INFO - PROCESS | 2872 | 07:28:39 INFO - PROCESS | 2872 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:67.50 stddev:8.47 (12.6%) stddev-sans-first:8.74 07:28:39 INFO - PROCESS | 2872 | Values: 65.0 65.0 64.0 65.0 68.0 85.0 82.0 81.0 67.0 64.0 07:28:39 INFO - PROCESS | 2872 | -------- Summary: end -------- 07:28:39 INFO - PROCESS | 2872 | 07:28:40 INFO - TEST-INFO | 2872: exit 0 07:28:40 INFO - TEST-OK | kraken | took 60673ms 07:28:40 INFO - SUITE-END | took 1176s 07:28:41 INFO - Completed test suite (00:19:36) 07:28:41 INFO - javascript benchmark 07:28:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2920.7955443118976, 3379.577552805899, 3393.9151521211984, 3408.897733068006, 3412.4369479035954, 5573.693446860431, 5594.66432014079, 5595.692236926968, 5678.403767604399, 5696.544276457884, 5592.86017849554, 5679.659220446766, 5682.687452190128, 5684.630499017571, 5697.088465845465, 4519.276915693474, 5065.189569668853, 5079.111155547783, 5122.282880396729, 5154.149565321717, 5089.414717307502, 5120.948790512084, 5205.453427390126, 5218.608604354669, 5223.947760522384, 5058.064258112261, 5092.770825312869, 5096.51583099605, 5109.233614957749, 5195.922061169085, 5180.766865491062, 5217.50433708797, 5221.0, 5225.346831646044, 5275.498827611371, 5029.119904016782, 5036.521530454601, 5094.516020978038, 5128.051310507568, 5142.07442660321, 4592.931106033412, 5513.5037846594, 5531.640443371168, 5565.220869078333, 5613.0, 6172.22847144107, 6192.071189321638, 6196.194494715658, 6204.7828326008375, 6216.347283535209, 5005.599552035828, 6167.229096362955, 6182.69086545671, 6283.465905398001, 6286.779962701329, 5747.252857128588, 5787.218725472049, 5792.536597072224, 5817.360090390053, 5819.53443724501, 6220.720067596969, 6267.5299352548745, 6281.089242059876, 6283.403076707706, 6333.20834895638, 5857.88284234313, 5901.1148327750325, 6083.665398403042, 6094.847628809315, 6114.571979961449, 6219.968900155471, 6271.717772700239, 6273.12176295319, 6275.874482510324, 6282.371762823773, 6201.069839524017, 6210.223722034746, 6224.128621992924, 6229.626222426669, 6244.875102497925, 5979.133025711248, 5997.550183736237, 6038.486728628116, 6239.625622462667, 6252.562320637603, 4787.353707249476, 4815.662903596714, 4817.879553011203, 4830.975845120822, 4835.830745923876, 4365.148795984816, 4397.966477877696, 4401.031773009932, 4421.491528474202, 4422.204003279377, 5633.971830140823, 5674.177244299553, 5693.9430605693415, 5717.199592057115, 5721.256236689203, 6212.223472065992, 6266.655333956585, 6271.7804876830005, 6295.0, 6316.557840951089, 5527.309086364205, 5573.303337082864, 5648.378678345379, 5649.915251271234, 5676.432356764373, 5262.842114736564, 5306.893862122736, 5314.1497360422145, 5330.173823057432, 5331.067063263913, 6216.191895053614, 6248.9062664060075, 6252.781152659635, 6266.686665666698, 6281.309056003744, 6102.389761023862, 6112.047575716935, 6253.656048917172, 6259.593126446766, 6267.5299352549655, 6208.658523781235, 6241.843953901189, 6263.467605253422, 6307.747690092345, 6335.968320158369, 6163.660998645118, 6173.722182501709, 6178.41305076017, 6183.96908015457, 6189.783357582463, 6106.915739268716, 6131.662758548323, 6243.8126856194385, 6245.937540624535, 6285.811425657238, 5811.76752929895, 5930.673812940157, 5987.43119403656, 6018.969905150446, 6053.848653783691, 6025.517958563217, 6042.607230530001, 6055.969720151371, 6055.969720151371, 6075.544334174866, 5786.971065144648, 5846.0, 5854.443827836349, 5897.144913987534, 5906.497947674496, 6092.539120253178, 6111.74481099421, 6119.624418755002, 6127.853107062832, 6133.05924499315, 5946.881062378641, 5955.940440595712, 5965.850853728692, 5981.312149102909, 6083.452489275986], "value": 5640.144515239602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.7829172601643, 534.2067030459766, 548.9890202195953, 556.7522452508633, 560.2884336892149, 30975.535366969485, 32607.1848203795, 32696.509552356703, 32708.855190068352, 32881.506777398325, 33398.66601333986, 44809.77595112028, 45911.770441147746, 45915.540844591545, 48097.51902480974, 9664.420134791917, 9814.0, 9816.950915245414, 9832.31173817833, 9845.55694993727, 8685.95657021714, 9704.563294651758, 9792.55933482995, 9806.803863922752, 9815.754606134868, 8723.869141962876, 11727.824082638766, 11927.165098443113, 11952.163348565604, 12046.518139274462, 11815.113866460008, 11874.228175168586, 11881.702957426047, 11892.048636109133, 11914.225575337576, 8599.0, 11534.134939879543, 11618.244814087142, 11699.473523691455, 11820.0, 41621.375679364835, 41896.79051604722, 42066.78966605178, 42133.0, 42138.0, 2842.957355639667, 3553.8933831984823, 3657.122290650225, 3675.72432067596, 4112.218678451085, 28307.858460707565, 28324.716752832413, 29686.70313296861, 29869.40261194764, 29908.55137172944, 2741.4105967217142, 2766.833989960609, 2790.539560972437, 2836.773058155343, 2892.2769307673084, 545557.0, 554752.0, 556094.0, 556329.0, 557858.9999999959, 53892.65268368322, 60241.0, 67154.66422667855, 67156.0, 67186.66406667935, 58746.0, 69677.3032269681, 70122.29877701259, 70212.29787702058, 70362.24094397681, 14857.925710371595, 17686.823131768775, 17720.468385948443, 17724.55688607795, 17728.29086836538, 13444.46222151103, 13513.256770877697, 13524.729505409836, 13540.796888046687, 13553.457861685421, 14735.63160920964, 18124.006359300467, 19903.900480497505, 20177.596448070955, 20381.082851271727, 6931.930680693229, 8147.75556733299, 8242.711505097293, 8247.628856701464, 8266.297364723945, 892.9240265480081, 1052.55792567122, 1084.2735367303926, 1098.242212873115, 1155.4338374196752, 27896.721032789414, 28916.566251506243, 30276.55022096044, 30905.84547077295, 30920.0, 1066.221658189526, 1141.1213365708359, 1189.5182451107316, 1240.9193402428814, 1256.309030033478, 15631.296591653403, 15650.9217453912, 15848.832313826402, 15919.602009949613, 15948.840511594735, 713594.0, 717380.0, 720588.0, 723576.0, 726773.0, 63518.68240658767, 63844.36155638477, 63873.680631597475, 63950.680246598466, 64195.358046419875, 1349.7165595225113, 1403.052939265971, 1459.5986103821533, 1502.9549113526612, 1520.0879472316522, 29915.800395059618, 31057.0682879514, 31082.378352433276, 31654.525182121826, 32234.516482253253, 53197.670058248856, 53308.0, 53762.731186343815, 53802.73098634482, 53870.0, 558456.0, 559355.0, 560251.0, 561907.0, 564869.0, 23907.282781516584, 24752.25743227706, 25251.242462726146, 27686.0, 28112.578311324938, 46468.30297545607, 48239.0, 48246.0, 48710.75644621754, 48949.5105048945, 39579.80210098931, 39757.80121099376, 40280.395794062526, 40949.59050409458, 41351.96620084522, 37782.81108594439, 37941.430878536295, 39694.60305396909, 40953.97615059647, 41771.16457670891, 37578.0, 38996.805015974736, 39124.80437597794, 39834.80082599684, 40655.59344406518, 33437.0, 34089.147771305914, 34194.48708269328, 34249.0, 34630.480542792386, 25178.392946828324, 25266.242012739647, 25375.746242537338, 25536.61695074538, 25942.481150377265], "value": 20767.74474684441, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.2532751091703, 404.5893418180546, 407.338075627106, 413.1221155045528, 426.8804734674292, 20680.379588612348, 21001.57996840064, 21322.57354852902, 21510.784892151114, 22102.557948841015, 20052.39842804718, 21144.365669029914, 21403.571928561458, 21808.0, 21929.890350548227, 6086.087086936951, 6149.569530132892, 6163.291221509517, 6205.565610407273, 6226.501879849601, 5820.923129221108, 6106.389361063881, 6113.908291375633, 6125.938740612603, 6142.692865356737, 6079.817605471843, 6110.9916863717435, 6115.327313995457, 6115.357887421824, 6136.631802091866, 5925.4370834770625, 6033.487153591928, 6082.756689732405, 6093.360197179321, 6107.877842443148, 4509.864704058883, 5149.098907691139, 5322.0686379883755, 5331.813386531491, 5336.06618841705, 26917.461650767073, 26935.922563097647, 27125.72874271252, 27459.0, 27462.862685686643, 2098.643230650793, 2121.893905304744, 2135.8718476891436, 2175.4670105824016, 2813.1138691312376, 14850.628734281621, 14911.328990195467, 15378.846211537853, 15722.056676599326, 15781.921090394475, 1696.6681155293159, 1756.323815331094, 1776.5025792778042, 1793.7757780277466, 1816.8637352198505, 116688.0, 122081.0, 124537.75462245443, 125247.37376313152, 125329.37335313356, 10907.509162087566, 11117.110631149488, 11393.544258229744, 11413.828792568118, 11529.48117334722, 11866.34735089561, 14264.35959864608, 14359.0, 14379.928100359431, 14393.78409323861, 8629.180227878342, 8859.867101993475, 8872.689455869013, 8983.46099234048, 9001.099890011077, 8854.64581416749, 8857.867131993025, 8857.867131993025, 8976.910230897738, 9014.323925705467, 6772.525923185428, 6905.447564194852, 6917.550359226634, 6927.376536111774, 6939.0, 1722.6813039587466, 1845.7415961765364, 1890.423420856652, 1900.4013735673495, 1917.1756144857845, 747.4506237915128, 789.455275859655, 816.3102178659024, 1053.257453495284, 1054.878688950765, 21570.460738481663, 22338.553228935656, 22431.887840560692, 22489.987950542265, 22666.546669066527, 801.6633014134078, 1039.1946241662772, 1046.1108058150662, 1079.4440862955585, 1092.7049696581878, 11308.830367544493, 11660.242084264495, 11673.768704786376, 11816.46825892837, 11829.349385783864, 516997.3951693128, 575999.0, 578062.0, 589874.0, 602353.0, 132725.33637331752, 133670.33164834307, 134518.0, 145536.0, 157130.0726430987, 918.4030380252756, 932.2122806228726, 1026.1688032693542, 1046.4663021858908, 1078.5524007536785, 19101.331453399063, 19271.13279902436, 19300.613987719888, 19310.517237069187, 19399.612007760046, 38576.0, 54801.0, 54806.90386192333, 55850.72074639601, 56775.43224567702, 73601.0, 76819.0, 81101.59449202716, 81470.37059258664, 82130.17869821226, 5389.676619402849, 7744.961275193588, 7770.45606807518, 7978.3218426434405, 8662.523561204194, 11633.825492617449, 12104.09219308573, 12686.682832929251, 12717.682057948625, 13375.598732038054, 11305.321680699184, 11361.943190283995, 11394.550171713121, 11502.769944601228, 11660.41697915084, 11162.9953304203, 11195.664130075786, 11478.417356095146, 11480.425978701198, 11616.477258523219, 11311.321320720783, 11455.484503197542, 11715.0, 11829.17195796286, 11948.223365481215, 7583.165851756192, 7640.159582445815, 7702.691892324468, 7818.100918394457, 7960.601969901598, 10966.232363734782, 11181.608643697431, 11285.43572821372, 11306.095512358826, 11526.308421494738], "value": 9938.850319750443, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.9897422317267, 1587.380921440638, 1593.442295196681, 1825.4432398118577, 1952.1215453046125, 8079.87880181797, 8891.288696904248, 9129.406588571755, 9164.037776033512, 9274.258059355252, 8847.867281990759, 9059.365844390895, 9073.546322683856, 9086.0, 9086.182243598076, 2412.3245491262473, 2457.6682147910074, 2458.9754102459015, 2461.212412028152, 2463.6550882876413, 2086.9252335047463, 2379.7501262367464, 2408.229366602679, 2444.963325550118, 2455.17751553229, 2414.1791790791126, 2424.83026188166, 2444.8533088014774, 2447.6940382452194, 2449.730529641738, 2379.916702915407, 2402.1592442645024, 2406.843555168917, 2407.987960060206, 2426.2721183645012, 2397.4126339047043, 2416.1905761569897, 2420.8063354931564, 2422.7819496245247, 2422.903083876643, 8786.209241168262, 8948.373613847034, 9040.095990400972, 9051.818963620757, 9063.229625481841, 4602.516735742733, 5617.101263797814, 5623.072193088176, 5637.71811409432, 5639.71801409932, 3516.578010638714, 3614.3855544557487, 3660.1764602964386, 3680.7423480356388, 3685.63143685632, 1048.6906362622967, 1065.9946700266448, 1068.4230515521608, 1070.7590792071721, 1073.6188653028141, 12241.449134788956, 12362.0, 12380.133390662657, 12381.814272785825, 12382.380880955916, 4092.2633925893624, 4640.11837750833, 4766.833160839354, 4808.927866082012, 4813.470518242991, 9546.761330966643, 9560.521973901277, 9567.473788941676, 9567.952160239154, 9569.904300957041, 4583.358329833826, 5391.56867450603, 5394.703291319015, 5398.217258497496, 5398.379186393537, 4502.144592527409, 4584.404027476407, 4721.0, 4980.526849949249, 5059.72171530562, 3821.8662346817728, 3845.769253844778, 3852.460655508231, 3887.436321733333, 3996.0009997500624, 1081.561967403203, 1232.6610182199968, 1239.783037968352, 1247.2703468470866, 1248.4569212392457, 919.9310051746146, 926.4070994563485, 935.6163972771132, 937.3110763588757, 1007.8941711120374, 5079.517445842639, 5555.833325000256, 5947.078202878561, 6089.939100608937, 6170.50635949123, 440.80163926233325, 457.1017949728732, 458.6949678463851, 458.86463493269565, 477.164961317694, 3601.4597810328137, 3677.8344974476213, 3864.7294689371474, 3933.8426462941734, 3985.0, 10772.407517586607, 11029.22795404314, 11274.492647830868, 11285.492152852978, 11315.094792416587, 9474.621015159455, 11209.607663731731, 11212.0, 11218.439078046065, 11227.382493962747, 8209.548474833942, 8469.407141500036, 8481.575921203916, 8563.015253245832, 8565.828683426296, 5912.556558258234, 5984.371640977809, 6043.879122417439, 6043.909341359971, 6053.939460605514, 9889.901100988898, 10251.538680759235, 10279.434631095362, 10429.634962776267, 10434.530446129791, 10118.392896425943, 10371.94814025925, 10538.104261137889, 10615.363078215332, 10669.626563070255, 7681.0, 7750.92249077502, 7817.452778305464, 7846.5292082475235, 7878.290953813955, 8487.575621219037, 8522.147785221676, 8539.0, 8567.100454452193, 8641.222289993932, 7569.243075692387, 7728.729494467667, 7777.883331749915, 7865.174156713462, 7877.487963282369, 7601.923980760343, 7623.923760762544, 7651.770446886602, 7800.531968081933, 7825.843483130419, 7164.641767911688, 7270.9636451817405, 7348.853022939405, 7363.410927125924, 7382.151052629017, 4447.799849006868, 4956.95903860179, 4988.8752781180765, 5024.773885175103, 5063.924041139312, 4993.975030124826, 5021.648484606042, 5028.597712182944, 5092.134337162617, 5242.18746094356], "value": 4577.8439379485535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.5751057317335, 322.4614893128475, 331.62029476249694, 334.7823914455602, 337.45501015859395, 4755.405574303212, 5273.8924825786635, 5411.026015317247, 5441.83674489765, 5478.01395748765, 5396.838094857151, 5417.918731219035, 5423.83728488145, 5462.781488740456, 5480.780768769245, 3261.5107733839936, 3632.7648599476174, 3698.6486283802997, 4075.572064933184, 4136.31750761124, 4017.1965606878593, 4070.5725898780647, 4071.7581137752973, 4091.713580049398, 4115.732477388975, 3702.481652568629, 3735.1969326594776, 4057.4116753070934, 4071.109512246309, 4089.5705950875176, 3350.977951724723, 3601.927961440783, 3887.863924762634, 4045.3211917054387, 4100.446439730628, 2699.460107978412, 3087.8729263818545, 3283.96716032839, 3541.140901026956, 3571.910702232465, 4239.1521695660995, 4529.88123420536, 4750.881227969328, 4778.566430071, 5195.5324020837925, 4365.585269399402, 4553.567411095941, 4722.505346043903, 4731.195696731564, 4785.784639691222, 5450.972745136289, 5463.644863083927, 5671.958095085766, 5689.216670913837, 5692.23154874095, 3970.7816070116132, 3973.284808734427, 3988.541317748467, 3995.4206640037323, 4020.336644453662, 4762.618990480753, 5464.808731694372, 5916.497097746654, 5952.839196356669, 6054.485368743662, 3412.19813343864, 3566.625504322061, 4030.8992275193355, 4033.2740106780475, 4042.312806822847, 4115.506139263248, 4119.608637179464, 4126.030382860025, 4134.607212314825, 4134.937975930364, 4153.958460415418, 4672.719636821802, 4708.552687494683, 4738.360321356573, 4746.667733258639, 4659.9767001165455, 5743.396943320975, 5755.3381361143165, 5771.451712087345, 5797.826065218051, 1330.813686083949, 1354.5462270139615, 1357.253510569183, 1372.30012693527, 1372.997711670486, 802.1937952357843, 824.1593574553922, 845.5118990576507, 847.8685803700437, 854.5515351479307, 4416.646668266595, 4842.346283251786, 4846.61227101831, 5288.699415754159, 5288.867778305573, 5843.386444423274, 5865.853353666193, 5881.970655135334, 5894.320118766131, 5895.4399332063385, 4555.63554915606, 5089.033083714083, 5181.145111056709, 5323.281357016753, 5323.30796996388, 2903.4918889194305, 3063.9387212256074, 3096.7058129477664, 3097.822827325602, 3116.532520122, 5169.510580358243, 5606.887862242732, 5638.393057978456, 5674.2623458950075, 5913.05391137416, 5220.451852555503, 5242.7378631068295, 5446.494612478305, 5600.047991841397, 5829.0, 5869.647821130746, 6015.4586087252355, 6114.296855861633, 6129.938700612936, 6143.1706719592285, 4850.442199147144, 5067.949320506896, 5679.148127780866, 5905.435059709094, 5917.911231331447, 5138.586888605514, 5220.963824108525, 5431.701256430934, 5756.683382414008, 5869.589128760946, 4975.676581022222, 5477.260569823023, 6149.477294430024, 6152.231495826985, 6153.815385538441, 3467.9076091031243, 3649.9817500912327, 3852.8073596320633, 3945.0669172106036, 3967.70242231828, 5436.320459942507, 5866.472017518444, 5886.705664716661, 5920.940790592039, 5941.910871336847, 5719.828405147852, 5758.782868595537, 5776.480116789509, 5814.04068328738, 5818.352649544362, 5037.0, 5477.911726512358, 5717.7141142941855, 5736.282964629421, 5741.25363702716, 4889.410941443974, 4973.303737476828, 5390.730463476889, 5620.241267429008, 5666.781641947001, 5504.339479262514, 5543.307086614173, 5735.0, 5741.0, 5756.395578464201, 5222.42553319127, 5326.547243484347, 5555.055640541199, 5643.689597072036, 5657.444202844086], "value": 4127.740432330735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.6684323092609, 707.1867352544576, 730.9195988441271, 761.5392687424109, 781.6091954022988, 15122.546323610295, 15465.536033918972, 17063.573410664754, 17844.643107137883, 17864.10679466025, 16682.58293542662, 16766.32934682611, 16809.24358403872, 16809.915950420233, 16865.241064152113, 757.3751654884715, 782.3545574900729, 900.522722956834, 943.8206740719276, 953.7615596100975, 939.8496240601505, 946.4415994563207, 946.607158029417, 948.7438391634255, 951.2675240065146, 918.7611221082533, 986.058314309836, 991.7818080022383, 992.3450522655049, 993.8161440133598, 792.8414317136596, 962.7833737409097, 981.6024510073431, 995.3381001633904, 998.7902540466511, 736.5507040705219, 738.6233021159247, 738.9113306403266, 740.2190688823289, 742.8105833012547, 15335.463258785889, 15375.538733838002, 15379.384824607003, 15382.230888455644, 15391.384344626202, 12073.57742479009, 12173.756524869454, 12184.756304873852, 12202.0, 12473.93763031188, 10776.19178561611, 10905.236633435663, 11059.668209953714, 11091.83362249567, 11459.0, 3936.6063393660406, 3993.8602148924647, 4062.1672557125717, 4098.364753463218, 4315.956840431619, 86925.56537217273, 87136.0, 87247.0, 88688.0, 88766.55616721875, 9195.90804091964, 9236.5843537041, 9477.28920330978, 9511.476868772146, 9817.361871478332, 20844.1662333508, 20874.95625218733, 20902.954852257328, 20912.68630970537, 20924.895375523025, 7247.456440766963, 7274.527155734861, 7436.851262974818, 7611.467197296136, 7630.0, 3425.7088147507743, 3906.4726261954843, 3953.6639385651965, 3978.622030907059, 3997.6801855851463, 3305.966940330614, 3410.897673069812, 3411.3006833599547, 3413.4879768034994, 3414.5219669246326, 648.1476857931805, 657.4346062386344, 660.7753363856312, 675.2336098528206, 711.7081996381562, 537.5242910024656, 592.7747455966705, 603.0682595390133, 607.3868965209228, 613.3927411862313, 8194.50832950013, 8214.794630134174, 8241.134680858424, 8309.542975136304, 8355.498670079814, 520.8828013696888, 555.8443635781988, 577.7746678795273, 582.3274118393263, 604.8246008657528, 3848.230353929225, 3917.118648304109, 3939.1136994176654, 3974.4634474345594, 4164.8334066637, 69975.0, 91655.54172229096, 92189.5390523043, 93619.65950851147, 98441.0, 78624.0, 80513.59743201247, 82183.5890820554, 83515.0, 84094.15905840986, 73234.9014764779, 73865.0, 74933.62533187299, 75077.62461187658, 75297.11757206114, 6957.686904089227, 7535.73624923125, 7622.504537205064, 7696.769096927102, 7776.300132988058, 40304.3954340691, 41384.79307603563, 41555.545555905395, 42032.78983605062, 42226.0, 39666.0, 44287.33568996532, 55369.44630553643, 55438.891222176135, 57371.713141434026, 9307.627694892128, 10357.326773759682, 10771.084457821173, 11288.871112888646, 11888.405579721153, 12167.634970950885, 12180.390980451119, 12291.631251062483, 12300.753984920431, 12305.876941230832, 11638.6508404748, 11824.290542567473, 11824.527018919147, 12102.818457722966, 12198.0, 11488.0, 11759.059275257789, 11980.820287695517, 12049.89126902806, 12480.75038499243, 11507.079433645122, 11515.827262591236, 11606.187566870238, 11809.0, 12440.68898277514, 9603.0, 9611.951940240255, 9914.454704991294, 9918.40489570628, 10248.38509689421, 6990.405815505739, 9345.158935695548, 9501.572429240563, 9682.515874206121, 9960.483786362556], "value": 6945.902845049408, "unit": "score"}], "name": "dromaeo_css", "value": 7311.855539932872, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 103.0, 110.0, 111.0, 114.0, 161.0, 163.0, 109.0, 110.0, 109.0], "value": 120.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 141.0, 237.0, 141.0, 158.0, 138.0, 116.0, 166.0, 116.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 184.0, 182.0, 186.0, 184.0, 186.0, 183.0, 183.0, 178.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 95.0, 81.0, 162.0, 57.0, 59.0, 57.0, 57.0, 113.0, 54.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 134.0, 79.0, 79.0, 77.0, 102.0, 96.0, 67.0, 124.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 86.0, 84.0, 85.0, 82.0, 85.0, 82.0, 86.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 92.0, 92.0, 99.0, 144.0, 87.0, 69.0, 70.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 58.0, 58.0, 58.0, 57.0, 118.0, 56.0, 55.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 74.0, 72.0, 71.0, 69.0, 67.0, 70.0, 69.0, 69.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 84.0, 84.0, 83.0, 84.0, 85.0, 85.0, 86.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 142.0, 152.0, 147.0, 130.0, 136.0, 135.0, 136.0, 215.0, 136.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 161.0, 163.0, 161.0, 163.0, 161.0, 163.0, 163.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 65.0, 68.0, 85.0, 82.0, 81.0, 67.0, 64.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1492.3, "alertThreshold": 2.0}]} 07:28:41 INFO - Return code: 0 07:28:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:28:41 ERROR - # TBPL SUCCESS # 07:28:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:28:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:28:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:28:41 INFO - 2016-05-16 07:28:41.153 screenresolution[2876:55722] starting screenresolution argv=screenresolution get 07:28:41 INFO - 2016-05-16 07:28:41.156 screenresolution[2876:55722] Display 0: 1600x1200x32@60 07:28:41 INFO - 2016-05-16 07:28:41.162 screenresolution[2877:55725] starting screenresolution argv=screenresolution list 07:28:41 INFO - Available Modes on Display 0 07:28:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:28:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:28:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:28:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:28:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:28:41 INFO - Intel Iris: 07:28:41 INFO - Chipset Model: Intel Iris 07:28:41 INFO - Type: GPU 07:28:41 INFO - Bus: Built-In 07:28:41 INFO - VRAM (Dynamic, Max): 1536 MB 07:28:41 INFO - Vendor: Intel (0x8086) 07:28:41 INFO - Device ID: 0x0a2e 07:28:41 INFO - Revision ID: 0x0009 07:28:41 INFO - Displays: 07:28:41 INFO - Display: 07:28:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:28:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:28:41 INFO - Display Serial Number: 1600x1200 60 07:28:41 INFO - Main Display: Yes 07:28:41 INFO - Mirror: Off 07:28:41 INFO - Online: Yes 07:28:41 INFO - Rotation: Supported 07:28:41 INFO - Return code: 0 07:28:41 INFO - Running post-action listener: _resource_record_post_action 07:28:41 INFO - ##### 07:28:41 INFO - ##### Finished run-tests step (success) 07:28:41 INFO - ##### 07:28:41 INFO - Running post-run listener: _resource_record_post_run 07:28:41 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 360448; Write bytes: 691629568; Read time: 2; Write time: 1881 07:28:41 INFO - TinderboxPrint: CPU usage
53.7% 07:28:41 INFO - TinderboxPrint: I/O read bytes / time
360,448 / 2 07:28:41 INFO - TinderboxPrint: I/O write bytes / time
691,629,568 / 1,881 07:28:41 INFO - TinderboxPrint: CPU idle
2,247.8 (46.3%) 07:28:41 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 07:28:41 INFO - TinderboxPrint: CPU user
1,635.6 (33.7%) 07:28:41 INFO - TinderboxPrint: Swap in / out
394,612,736 / 0 07:28:41 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 382349824; Write bytes: 376927744; Read time: 28290; Write time: 771 07:28:41 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 360448; Write bytes: 294029312; Read time: 2; Write time: 1055 07:28:41 INFO - Running post-run listener: _upload_blobber_files 07:28:41 INFO - Blob upload gear active. 07:28:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:28:41 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 07:28:41 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'] 07:28:41 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 07:28:42 INFO - (blobuploader) - INFO - Open directory for files ... 07:28:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:28:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:28:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:43 INFO - (blobuploader) - INFO - Done attempting. 07:28:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:28:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:44 INFO - (blobuploader) - INFO - Done attempting. 07:28:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:28:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:28:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:46 INFO - (blobuploader) - INFO - Done attempting. 07:28:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:28:46 INFO - Return code: 0 07:28:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:28:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:28:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07d819c3ff8fafee67b29ef86d209e27c70850999c07b14b5a81b900a3785f9f319a282e1696847d80e6a16baea245710491990b6d6606f481b5f207a4401d2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebce2a1180f29669d47cfdcfd386ce6ab4383d26bf433313b84dcd55039bed7009e6db32c92a5cc4d99b94946296d0b2f61b897676cf0f477d311d5d629dddb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76f971ea8851b8e176551b5809e1375d238cde2d79e8558b7ac5e6e40a703dcfd906ce9e1e69de38dad19f506699f77a014d744b7c080109ffd6dc402b43bb45"} 07:28:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:28:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:28:46 INFO - Contents: 07:28:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07d819c3ff8fafee67b29ef86d209e27c70850999c07b14b5a81b900a3785f9f319a282e1696847d80e6a16baea245710491990b6d6606f481b5f207a4401d2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebce2a1180f29669d47cfdcfd386ce6ab4383d26bf433313b84dcd55039bed7009e6db32c92a5cc4d99b94946296d0b2f61b897676cf0f477d311d5d629dddb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76f971ea8851b8e176551b5809e1375d238cde2d79e8558b7ac5e6e40a703dcfd906ce9e1e69de38dad19f506699f77a014d744b7c080109ffd6dc402b43bb45"} 07:28:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:28:46 INFO - Copying logs to upload dir... 07:28:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:28:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.237109 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-16 07:28:46.871203) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 07:28:46.874764) ========= 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.DyeZAvn5HD/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.GDo9Ko82au/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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/07d819c3ff8fafee67b29ef86d209e27c70850999c07b14b5a81b900a3785f9f319a282e1696847d80e6a16baea245710491990b6d6606f481b5f207a4401d2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebce2a1180f29669d47cfdcfd386ce6ab4383d26bf433313b84dcd55039bed7009e6db32c92a5cc4d99b94946296d0b2f61b897676cf0f477d311d5d629dddb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76f971ea8851b8e176551b5809e1375d238cde2d79e8558b7ac5e6e40a703dcfd906ce9e1e69de38dad19f506699f77a014d744b7c080109ffd6dc402b43bb45"} build_url:https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/UeL7YIV5QWSVbBIUGcT7ew/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/07d819c3ff8fafee67b29ef86d209e27c70850999c07b14b5a81b900a3785f9f319a282e1696847d80e6a16baea245710491990b6d6606f481b5f207a4401d2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebce2a1180f29669d47cfdcfd386ce6ab4383d26bf433313b84dcd55039bed7009e6db32c92a5cc4d99b94946296d0b2f61b897676cf0f477d311d5d629dddb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76f971ea8851b8e176551b5809e1375d238cde2d79e8558b7ac5e6e40a703dcfd906ce9e1e69de38dad19f506699f77a014d744b7c080109ffd6dc402b43bb45"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 07:28:46.905901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:28:46.906201) ========= 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.DyeZAvn5HD/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.GDo9Ko82au/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.005215 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 07:28:46.955017) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 07:28:46.955316) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 07:28:46.955640) ========= ========= Total master_lag: 0.17 =========