builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1464717375.47 results: success (0) buildid: 20160531091142 builduid: f9b9e1951faf4dde8f928544fec6eaa9 revision: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:15.473894) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:15.474315) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:15.474591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:15.501941) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:15.502234) ========= 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.FaOixRfuw5/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.9TVBnd82sb/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 10:56:15-- 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% 12.7M=0.001s 2016-05-31 10:56:15 (12.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.481029 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:16.026342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:16.026669) ========= 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.FaOixRfuw5/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.9TVBnd82sb/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.028149 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:16.077669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 10:56:16.078526) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 --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 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FaOixRfuw5/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.9TVBnd82sb/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 10:56:16,154 truncating revision to first 12 chars 2016-05-31 10:56:16,155 Setting DEBUG logging. 2016-05-31 10:56:16,155 attempt 1/10 2016-05-31 10:56:16,155 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6fb5bb73ea6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 10:56:17,244 unpacking tar archive at: fx-team-6fb5bb73ea6a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.354308 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 10:56:17.454496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:17.454832) ========= script_repo_revision: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:17.455268) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:17.455535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 10:56:17.471281) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-31 10:56:17.471553) ========= /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.FaOixRfuw5/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.9TVBnd82sb/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:56:17 INFO - MultiFileLogger online at 20160531 10:56:17 in /builds/slave/test 10:56:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:56:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:56:17 INFO - {'append_to_log': False, 10:56:17 INFO - 'base_work_dir': '/builds/slave/test', 10:56:17 INFO - 'blob_upload_branch': 'Fx-Team', 10:56:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:56:17 INFO - 'branch': 'Fx-Team', 10:56:17 INFO - 'buildbot_json_path': 'buildprops.json', 10:56:17 INFO - 'config_files': ('talos/mac_config.py',), 10:56:17 INFO - 'default_actions': ('clobber', 10:56:17 INFO - 'read-buildbot-config', 10:56:17 INFO - 'download-and-extract', 10:56:17 INFO - 'populate-webroot', 10:56:17 INFO - 'create-virtualenv', 10:56:17 INFO - 'install', 10:56:17 INFO - 'run-tests'), 10:56:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:56:17 INFO - 'download_minidump_stackwalk': True, 10:56:17 INFO - 'download_symbols': 'ondemand', 10:56:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:56:17 INFO - 'tooltool.py': '/tools/tooltool.py', 10:56:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:56:17 INFO - '/tools/misc-python/virtualenv.py')}, 10:56:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:56:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:56:17 INFO - 'installer_path': 'installer.exe', 10:56:17 INFO - 'log_level': 'info', 10:56:17 INFO - 'log_name': 'talos', 10:56:17 INFO - 'log_to_console': True, 10:56:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:56:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:56:17 INFO - 'opt_config_files': (), 10:56:17 INFO - 'pip_index': False, 10:56:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:56:17 INFO - 'cmd': ('bash', 10:56:17 INFO - '-c', 10:56:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:56:17 INFO - 'enabled': True, 10:56:17 INFO - 'halt_on_failure': False, 10:56:17 INFO - 'name': 'check_screen_resolution'},), 10:56:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:56:17 INFO - 'cmd': ('bash', 10:56:17 INFO - '-c', 10:56:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:56:17 INFO - 'enabled': True, 10:56:17 INFO - 'halt_on_failure': False, 10:56:17 INFO - 'name': 'check_screen_resolution'},), 10:56:17 INFO - 'run_cmd_checks_enabled': True, 10:56:17 INFO - 'sps_profile': False, 10:56:17 INFO - 'sps_profile_interval': 0, 10:56:17 INFO - 'suite': 'dromaeojs', 10:56:17 INFO - 'system_bits': '32', 10:56:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:56:17 INFO - 'title': 't-yosemite-r7-0164', 10:56:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:56:17 INFO - 'use_talos_json': True, 10:56:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:56:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:56:17 INFO - 'work_dir': 'build'} 10:56:17 INFO - ##### 10:56:17 INFO - ##### Running clobber step. 10:56:17 INFO - ##### 10:56:17 INFO - Running pre-action listener: _resource_record_pre_action 10:56:17 INFO - Running main action method: clobber 10:56:17 INFO - rmtree: /builds/slave/test/build 10:56:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:56:19 INFO - Running post-action listener: _resource_record_post_action 10:56:19 INFO - ##### 10:56:19 INFO - ##### Finished clobber step (success) 10:56:19 INFO - ##### 10:56:19 INFO - ##### 10:56:19 INFO - ##### Running read-buildbot-config step. 10:56:19 INFO - ##### 10:56:19 INFO - Running pre-action listener: _resource_record_pre_action 10:56:19 INFO - Running main action method: read_buildbot_config 10:56:19 INFO - Using buildbot properties: 10:56:19 INFO - { 10:56:19 INFO - "project": "", 10:56:19 INFO - "product": "firefox", 10:56:19 INFO - "script_repo_revision": "production", 10:56:19 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:56:19 INFO - "repository": "", 10:56:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:56:19 INFO - "buildid": "20160531091142", 10:56:19 INFO - "pgo_build": "False", 10:56:19 INFO - "basedir": "/builds/slave/test", 10:56:19 INFO - "buildnumber": 796, 10:56:19 INFO - "slavename": "t-yosemite-r7-0164", 10:56:19 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:56:19 INFO - "platform": "yosemite_r7", 10:56:19 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:56:19 INFO - "branch": "fx-team", 10:56:19 INFO - "repo_path": "integration/fx-team", 10:56:19 INFO - "revision": "6fb5bb73ea6a50d383c23d7d346343b28b14b9f5", 10:56:19 INFO - "stage_platform": "macosx64", 10:56:19 INFO - "builduid": "f9b9e1951faf4dde8f928544fec6eaa9", 10:56:19 INFO - "slavebuilddir": "test" 10:56:19 INFO - } 10:56:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:56:19 INFO - Running post-action listener: _resource_record_post_action 10:56:19 INFO - ##### 10:56:19 INFO - ##### Finished read-buildbot-config step (success) 10:56:19 INFO - ##### 10:56:19 INFO - ##### 10:56:19 INFO - ##### Running download-and-extract step. 10:56:19 INFO - ##### 10:56:19 INFO - Running pre-action listener: _resource_record_pre_action 10:56:19 INFO - Running main action method: download_and_extract 10:56:19 INFO - mkdir: /builds/slave/test/build/tests 10:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:19 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:56:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:56:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:56:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 10:56:27 INFO - Downloaded 1199 bytes. 10:56:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:56:27 INFO - Using the following test package requirements: 10:56:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:56:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:56:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:56:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'jsshell-mac.zip'], 10:56:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:56:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:56:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:56:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:56:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:56:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:56:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:56:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:27 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:56:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:56:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:56:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:56:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:56:36 INFO - Downloaded 18687095 bytes. 10:56:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:56:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:56:39 INFO - Return code: 0 10:56:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:39 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:56:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:56:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:56:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:56:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:56:41 INFO - Downloaded 18687095 bytes. 10:56:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:56:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:56:45 INFO - Return code: 0 10:56: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')]} 10:56:45 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:56:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:56:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:56:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:56:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:56:46 INFO - Downloaded 10834055 bytes. 10:56:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:56:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:56:46 INFO - Return code: 0 10:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:56:46 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:56:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:56:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:56:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:56:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 10:56:53 INFO - Downloaded 110986559 bytes. 10:56:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:56:53 INFO - mkdir: /builds/slave/test/properties 10:56:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:56:53 INFO - Writing to file /builds/slave/test/properties/build_url 10:56:53 INFO - Contents: 10:56:53 INFO - build_url:https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:56:54 INFO - Running post-action listener: _resource_record_post_action 10:56:54 INFO - Running post-action listener: set_extra_try_arguments 10:56:54 INFO - ##### 10:56:54 INFO - ##### Finished download-and-extract step (success) 10:56:54 INFO - ##### 10:56:54 INFO - ##### 10:56:54 INFO - ##### Running populate-webroot step. 10:56:54 INFO - ##### 10:56:54 INFO - Running pre-action listener: _resource_record_pre_action 10:56:54 INFO - Running main action method: populate_webroot 10:56:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:56:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:56:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:56:54 INFO - '--tppagecycles', 10:56:54 INFO - '7'], 10:56:54 INFO - 'tests': ['tsvgm']}}, 10:56:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:56:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:56:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:56:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:56:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:56:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:56:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['damp', 'tps']}, 10:56:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'tests': ['damp', 'tps']}, 10:56:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['dromaeo_dom']}, 10:56:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:56:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['a11yr', 10:56:54 INFO - 'ts_paint', 10:56:54 INFO - 'tpaint', 10:56:54 INFO - 'sessionrestore', 10:56:54 INFO - 'sessionrestore_no_auto_restore', 10:56:54 INFO - 'tabpaint']}, 10:56:54 INFO - 'other-e10s': {'tests': ['a11yr', 10:56:54 INFO - 'ts_paint', 10:56:54 INFO - 'tpaint', 10:56:54 INFO - 'sessionrestore', 10:56:54 INFO - 'sessionrestore_no_auto_restore', 10:56:54 INFO - 'tabpaint']}, 10:56:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:56:54 INFO - 'ts_paint', 10:56:54 INFO - 'tpaint', 10:56:54 INFO - 'sessionrestore', 10:56:54 INFO - 'sessionrestore_no_auto_restore', 10:56:54 INFO - 'tabpaint']}, 10:56:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:56:54 INFO - 'ts_paint', 10:56:54 INFO - 'tpaint', 10:56:54 INFO - 'sessionrestore', 10:56:54 INFO - 'sessionrestore_no_auto_restore', 10:56:54 INFO - 'tabpaint']}, 10:56:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['a11yr', 10:56:54 INFO - 'ts_paint', 10:56:54 INFO - 'tpaint', 10:56:54 INFO - 'sessionrestore', 10:56:54 INFO - 'sessionrestore_no_auto_restore', 10:56:54 INFO - 'tabpaint']}, 10:56:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['a11yr', 10:56:54 INFO - 'ts_paint', 10:56:54 INFO - 'tpaint', 10:56:54 INFO - 'sessionrestore', 10:56:54 INFO - 'sessionrestore_no_auto_restore', 10:56:54 INFO - 'tabpaint']}, 10:56:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['tsvgx', 10:56:54 INFO - 'tsvgr_opacity', 10:56:54 INFO - 'tart', 10:56:54 INFO - 'tscrollx', 10:56:54 INFO - 'cart']}, 10:56:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:56:54 INFO - 'tsvgr_opacity', 10:56:54 INFO - 'tart', 10:56:54 INFO - 'tscrollx', 10:56:54 INFO - 'cart']}, 10:56:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'talos_options': ['--disable-e10s'], 10:56:54 INFO - 'tests': ['tp5o']}, 10:56:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'tests': ['tp5o']}, 10:56:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'talos_options': ['--disable-e10s', 10:56:54 INFO - '--xperf_path', 10:56:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:56:54 INFO - 'tests': ['tp5n']}, 10:56:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:56:54 INFO - 'talos_options': ['--xperf_path', 10:56:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:56:54 INFO - 'tests': ['tp5n']}}, 10:56:54 INFO - 'talos.zip': {'path': '', 10:56:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:56:54 INFO - Running post-action listener: _resource_record_post_action 10:56:54 INFO - ##### 10:56:54 INFO - ##### Finished populate-webroot step (success) 10:56:54 INFO - ##### 10:56:54 INFO - ##### 10:56:54 INFO - ##### Running create-virtualenv step. 10:56:54 INFO - ##### 10:56:54 INFO - Running pre-action listener: _resource_record_pre_action 10:56:54 INFO - Running main action method: create_virtualenv 10:56:54 INFO - Creating virtualenv /builds/slave/test/build/venv 10:56: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 10:56:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:56:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:56:54 INFO - Using real prefix '/tools/python27' 10:56:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:56:55 INFO - Installing distribute.............................................................................................................................................................................................done. 10:56:58 INFO - Installing pip.................done. 10:56:58 INFO - Return code: 0 10:56:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:56: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 10:56: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 10:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:58 INFO - 'HOME': '/Users/cltbld', 10:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:58 INFO - 'LOGNAME': 'cltbld', 10:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:58 INFO - 'MOZ_NO_REMOTE': '1', 10:56:58 INFO - 'NO_EM_RESTART': '1', 10:56:58 INFO - 'PAGER': '/bin/cat', 10:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:58 INFO - 'PWD': '/builds/slave/test', 10:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:58 INFO - 'SHELL': '/bin/bash', 10:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:56:58 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:58 INFO - 'USER': 'cltbld', 10:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:58 INFO - 'XPC_FLAGS': '0x0', 10:56:58 INFO - 'XPC_SERVICE_NAME': '0', 10:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56: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 10:56:58 INFO - Cleaning up... 10:56:58 INFO - Return code: 0 10:56:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:56: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')]} 10:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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')]} 10:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:56: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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:56: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 10:56: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 10:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:56:58 INFO - 'HOME': '/Users/cltbld', 10:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:56:58 INFO - 'LOGNAME': 'cltbld', 10:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:56:58 INFO - 'MOZ_NO_REMOTE': '1', 10:56:58 INFO - 'NO_EM_RESTART': '1', 10:56:58 INFO - 'PAGER': '/bin/cat', 10:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:56:58 INFO - 'PWD': '/builds/slave/test', 10:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:56:58 INFO - 'SHELL': '/bin/bash', 10:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:56:58 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:56:58 INFO - 'USER': 'cltbld', 10:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:56:58 INFO - 'XPC_FLAGS': '0x0', 10:56:58 INFO - 'XPC_SERVICE_NAME': '0', 10:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:56:58 INFO - Downloading/unpacking psutil>=3.1.1 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:56: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 10:57:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:57:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:02 INFO - Installing collected packages: psutil 10:57:02 INFO - Running setup.py install for psutil 10:57:02 INFO - building 'psutil._psutil_osx' extension 10:57:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:57:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:57:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:57: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 10:57:02 INFO - building 'psutil._psutil_posix' extension 10:57: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 10:57:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:57:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:57:02 INFO - ^ 10:57:03 INFO - 1 warning generated. 10:57:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:57:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:03 INFO - Successfully installed psutil 10:57:03 INFO - Cleaning up... 10:57:03 INFO - Return code: 0 10:57:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:03 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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:03 INFO - 'HOME': '/Users/cltbld', 10:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:03 INFO - 'LOGNAME': 'cltbld', 10:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:03 INFO - 'MOZ_NO_REMOTE': '1', 10:57:03 INFO - 'NO_EM_RESTART': '1', 10:57:03 INFO - 'PAGER': '/bin/cat', 10:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:03 INFO - 'PWD': '/builds/slave/test', 10:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:03 INFO - 'SHELL': '/bin/bash', 10:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:03 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:03 INFO - 'USER': 'cltbld', 10:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:03 INFO - 'XPC_FLAGS': '0x0', 10:57:03 INFO - 'XPC_SERVICE_NAME': '0', 10:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:57:06 INFO - Installing collected packages: mozsystemmonitor 10:57:06 INFO - Running setup.py install for mozsystemmonitor 10:57:06 INFO - Successfully installed mozsystemmonitor 10:57:06 INFO - Cleaning up... 10:57:06 INFO - Return code: 0 10:57:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:57:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:06 INFO - 'HOME': '/Users/cltbld', 10:57:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:06 INFO - 'LOGNAME': 'cltbld', 10:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:06 INFO - 'MOZ_NO_REMOTE': '1', 10:57:06 INFO - 'NO_EM_RESTART': '1', 10:57:06 INFO - 'PAGER': '/bin/cat', 10:57:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:06 INFO - 'PWD': '/builds/slave/test', 10:57:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:06 INFO - 'SHELL': '/bin/bash', 10:57:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:06 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:06 INFO - 'USER': 'cltbld', 10:57:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:06 INFO - 'XPC_FLAGS': '0x0', 10:57:06 INFO - 'XPC_SERVICE_NAME': '0', 10:57:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:06 INFO - Downloading/unpacking blobuploader==1.2.4 10:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:08 INFO - Downloading blobuploader-1.2.4.tar.gz 10:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:57:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:57:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:10 INFO - Downloading docopt-0.6.1.tar.gz 10:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:57:10 INFO - Installing collected packages: blobuploader, requests, docopt 10:57:10 INFO - Running setup.py install for blobuploader 10:57:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:57:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:57:10 INFO - Running setup.py install for requests 10:57:10 INFO - Running setup.py install for docopt 10:57:11 INFO - Successfully installed blobuploader requests docopt 10:57:11 INFO - Cleaning up... 10:57:11 INFO - Return code: 0 10:57:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:11 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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:11 INFO - 'HOME': '/Users/cltbld', 10:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:11 INFO - 'LOGNAME': 'cltbld', 10:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:11 INFO - 'MOZ_NO_REMOTE': '1', 10:57:11 INFO - 'NO_EM_RESTART': '1', 10:57:11 INFO - 'PAGER': '/bin/cat', 10:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:11 INFO - 'PWD': '/builds/slave/test', 10:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:11 INFO - 'SHELL': '/bin/bash', 10:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:11 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:11 INFO - 'USER': 'cltbld', 10:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:11 INFO - 'XPC_FLAGS': '0x0', 10:57:11 INFO - 'XPC_SERVICE_NAME': '0', 10:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-UnE7GZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-IXfubb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-oQSgOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-YY0nbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_g12lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-QuCH1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_uv4nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-x3GQth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-whmD6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-KhFoAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-X2aY6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ga6l3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8Lt7cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5v9HDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-c3CCX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-EshzEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-4PYUZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:57:13 INFO - Running setup.py install for manifestparser 10:57:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:57:13 INFO - Running setup.py install for mozcrash 10:57:13 INFO - Running setup.py install for mozdebug 10:57:13 INFO - Running setup.py install for mozdevice 10:57:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:57:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:57:13 INFO - Running setup.py install for mozfile 10:57:13 INFO - Running setup.py install for mozhttpd 10:57:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Running setup.py install for mozinfo 10:57:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Running setup.py install for mozInstall 10:57:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Running setup.py install for mozleak 10:57:14 INFO - Running setup.py install for mozlog 10:57:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Running setup.py install for moznetwork 10:57:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:57:14 INFO - Running setup.py install for mozprocess 10:57:14 INFO - Running setup.py install for mozprofile 10:57:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:57:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:57:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:57:15 INFO - Running setup.py install for mozrunner 10:57:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:57:15 INFO - Running setup.py install for mozscreenshot 10:57:15 INFO - Running setup.py install for moztest 10:57:15 INFO - Running setup.py install for mozversion 10:57:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:57:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:57:15 INFO - Cleaning up... 10:57:15 INFO - Return code: 0 10:57:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57: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 10:57: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 10:57:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:15 INFO - 'HOME': '/Users/cltbld', 10:57:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:15 INFO - 'LOGNAME': 'cltbld', 10:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:15 INFO - 'MOZ_NO_REMOTE': '1', 10:57:15 INFO - 'NO_EM_RESTART': '1', 10:57:15 INFO - 'PAGER': '/bin/cat', 10:57:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:15 INFO - 'PWD': '/builds/slave/test', 10:57:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:15 INFO - 'SHELL': '/bin/bash', 10:57:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:15 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:15 INFO - 'USER': 'cltbld', 10:57:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:15 INFO - 'XPC_FLAGS': '0x0', 10:57:15 INFO - 'XPC_SERVICE_NAME': '0', 10:57:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-70ueQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Gd7Frs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ir5nT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ePK95l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-YYTtpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57: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)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-muIuEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57: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)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-G0I_2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57: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)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Exf8F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57: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)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wnruBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57: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)) 10:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gjM65C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-GkYKCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Yqcgi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-AXndPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ADRUry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57: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)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8DIkhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57: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)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vFBpL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57: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)) 10:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-IFIjmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:20 INFO - Downloading blessings-1.6.tar.gz 10:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:57:20 INFO - Installing collected packages: blessings 10:57:20 INFO - Running setup.py install for blessings 10:57:20 INFO - Successfully installed blessings 10:57:20 INFO - Cleaning up... 10:57:20 INFO - Return code: 0 10:57:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:57:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:20 INFO - Reading from file tmpfile_stdout 10:57:20 INFO - Current package versions: 10:57:20 INFO - blessings == 1.6 10:57:20 INFO - blobuploader == 1.2.4 10:57:20 INFO - docopt == 0.6.1 10:57:20 INFO - manifestparser == 1.1 10:57:20 INFO - mozInstall == 1.12 10:57:20 INFO - mozcrash == 0.17 10:57:20 INFO - mozdebug == 0.1 10:57:20 INFO - mozdevice == 0.48 10:57:20 INFO - mozfile == 1.2 10:57:20 INFO - mozhttpd == 0.7 10:57:20 INFO - mozinfo == 0.9 10:57:20 INFO - mozleak == 0.1 10:57:20 INFO - mozlog == 3.2 10:57:20 INFO - moznetwork == 0.27 10:57:20 INFO - mozprocess == 0.22 10:57:20 INFO - mozprofile == 0.28 10:57:20 INFO - mozrunner == 6.11 10:57:20 INFO - mozscreenshot == 0.1 10:57:20 INFO - mozsystemmonitor == 0.3 10:57:20 INFO - moztest == 0.7 10:57:20 INFO - mozversion == 1.4 10:57:20 INFO - psutil == 3.1.1 10:57:20 INFO - requests == 1.2.3 10:57:20 INFO - wsgiref == 0.1.2 10:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57: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')]} 10:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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')]} 10:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57: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 10:57: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 10:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:20 INFO - 'HOME': '/Users/cltbld', 10:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:20 INFO - 'LOGNAME': 'cltbld', 10:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:20 INFO - 'MOZ_NO_REMOTE': '1', 10:57:20 INFO - 'NO_EM_RESTART': '1', 10:57:20 INFO - 'PAGER': '/bin/cat', 10:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:20 INFO - 'PWD': '/builds/slave/test', 10:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:20 INFO - 'SHELL': '/bin/bash', 10:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:20 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:20 INFO - 'USER': 'cltbld', 10:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:20 INFO - 'XPC_FLAGS': '0x0', 10:57:20 INFO - 'XPC_SERVICE_NAME': '0', 10:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57: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)) 10:57:21 INFO - Cleaning up... 10:57:21 INFO - Return code: 0 10:57:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:57: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')]} 10:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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')]} 10:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57: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 10:57: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 10:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:21 INFO - 'HOME': '/Users/cltbld', 10:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:21 INFO - 'LOGNAME': 'cltbld', 10:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:21 INFO - 'MOZ_NO_REMOTE': '1', 10:57:21 INFO - 'NO_EM_RESTART': '1', 10:57:21 INFO - 'PAGER': '/bin/cat', 10:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:21 INFO - 'PWD': '/builds/slave/test', 10:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:21 INFO - 'SHELL': '/bin/bash', 10:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:21 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:21 INFO - 'USER': 'cltbld', 10:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:21 INFO - 'XPC_FLAGS': '0x0', 10:57:21 INFO - 'XPC_SERVICE_NAME': '0', 10:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:21 INFO - Downloading/unpacking jsonschema 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:57:23 INFO - Downloading/unpacking functools32 (from jsonschema) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:57:24 INFO - warning: no files found matching '*.txt' 10:57:24 INFO - no previously-included directories found matching 'build' 10:57:24 INFO - no previously-included directories found matching 'dist' 10:57:24 INFO - no previously-included directories found matching '.git*' 10:57:24 INFO - Installing collected packages: jsonschema, functools32 10:57:24 INFO - Running setup.py install for functools32 10:57:24 INFO - warning: no files found matching '*.txt' 10:57:24 INFO - no previously-included directories found matching 'build' 10:57:24 INFO - no previously-included directories found matching 'dist' 10:57:24 INFO - no previously-included directories found matching '.git*' 10:57:24 INFO - Successfully installed jsonschema functools32 10:57:24 INFO - Cleaning up... 10:57:24 INFO - Return code: 0 10:57:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:57: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')]} 10:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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')]} 10:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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 0x100ec1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ec7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81cad853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, '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.FaOixRfuw5/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.9TVBnd82sb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57: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 10:57: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 10:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:24 INFO - 'HOME': '/Users/cltbld', 10:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:24 INFO - 'LOGNAME': 'cltbld', 10:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:24 INFO - 'MOZ_NO_REMOTE': '1', 10:57:24 INFO - 'NO_EM_RESTART': '1', 10:57:24 INFO - 'PAGER': '/bin/cat', 10:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:24 INFO - 'PWD': '/builds/slave/test', 10:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:24 INFO - 'SHELL': '/bin/bash', 10:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:57:24 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:24 INFO - 'USER': 'cltbld', 10:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:24 INFO - 'XPC_FLAGS': '0x0', 10:57:24 INFO - 'XPC_SERVICE_NAME': '0', 10:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:24 INFO - Downloading/unpacking flake8 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:57:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:57:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:57:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:57:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:57:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:57:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:57:28 INFO - no previously-included directories found matching 'docs/_build' 10:57:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:57:28 INFO - Running setup.py install for pep8 10:57:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:57:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:57:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:57:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:57:28 INFO - no previously-included directories found matching 'docs/_build' 10:57:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:57:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:57:28 INFO - Cleaning up... 10:57:28 INFO - Return code: 0 10:57:28 INFO - Running post-action listener: _resource_record_post_action 10:57:28 INFO - Running post-action listener: _start_resource_monitoring 10:57:28 INFO - Starting resource monitoring. 10:57:28 INFO - ##### 10:57:28 INFO - ##### Finished create-virtualenv step (success) 10:57:28 INFO - ##### 10:57:28 INFO - ##### 10:57:28 INFO - ##### Running install step. 10:57:28 INFO - ##### 10:57:28 INFO - Running pre-action listener: _resource_record_pre_action 10:57:28 INFO - Running main action method: install 10:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:29 INFO - Reading from file tmpfile_stdout 10:57:29 INFO - Detecting whether we're running mozinstall >=1.0... 10:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:57:29 INFO - Reading from file tmpfile_stdout 10:57:29 INFO - Output received: 10:57:29 INFO - Usage: mozinstall [options] installer 10:57:29 INFO - Options: 10:57:29 INFO - -h, --help show this help message and exit 10:57:29 INFO - -d DEST, --destination=DEST 10:57:29 INFO - Directory to install application into. [default: 10:57:29 INFO - "/builds/slave/test"] 10:57:29 INFO - --app=APP Application being installed. [default: firefox] 10:57:29 INFO - mkdir: /builds/slave/test/build/application 10:57: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'] 10:57: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 10:58:03 INFO - Reading from file tmpfile_stdout 10:58:03 INFO - Output received: 10:58:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:58:03 INFO - Running post-action listener: _resource_record_post_action 10:58:03 INFO - ##### 10:58:03 INFO - ##### Finished install step (success) 10:58:03 INFO - ##### 10:58:03 INFO - ##### 10:58:03 INFO - ##### Running run-tests step. 10:58:03 INFO - ##### 10:58:03 INFO - Running pre-action listener: _resource_record_pre_action 10:58:03 INFO - Running main action method: run_tests 10:58:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:58:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:58:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:58:03 INFO - 2016-05-31 10:58:03.570 screenresolution[1642:7664] starting screenresolution argv=screenresolution get 10:58:03 INFO - 2016-05-31 10:58:03.573 screenresolution[1642:7664] Display 0: 1600x1200x32@60 10:58:03 INFO - 2016-05-31 10:58:03.579 screenresolution[1643:7666] starting screenresolution argv=screenresolution list 10:58:03 INFO - Available Modes on Display 0 10:58:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:58:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:58:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:58:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:58:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:58:03 INFO - Intel Iris: 10:58:03 INFO - Chipset Model: Intel Iris 10:58:03 INFO - Type: GPU 10:58:03 INFO - Bus: Built-In 10:58:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:58:03 INFO - Vendor: Intel (0x8086) 10:58:03 INFO - Device ID: 0x0a2e 10:58:03 INFO - Revision ID: 0x0009 10:58:03 INFO - Displays: 10:58:03 INFO - Display: 10:58:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:58:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:58:03 INFO - Display Serial Number: 1600x1200 60 10:58:03 INFO - Main Display: Yes 10:58:03 INFO - Mirror: Off 10:58:03 INFO - Online: Yes 10:58:03 INFO - Rotation: Supported 10:58:03 INFO - Return code: 0 10:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:58:03 INFO - Python 2.7.3 10:58:03 INFO - Return code: 0 10:58:03 INFO - grabbing minidump binary from tooltool 10:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:58:03 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 0x100d65d30>, '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 0x100b89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d5cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:58:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:58:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:58:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:58:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:58:03 INFO - Return code: 0 10:58:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:58:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:58:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:58:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:58:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:58:05 INFO - Return code: 0 10:58:05 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/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:58:05 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/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FaOixRfuw5/Render', 10:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:58:05 INFO - 'HOME': '/Users/cltbld', 10:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:58:05 INFO - 'LOGNAME': 'cltbld', 10:58:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:58:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:58:05 INFO - 'MOZ_NO_REMOTE': '1', 10:58:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:58:05 INFO - 'NO_EM_RESTART': '1', 10:58:05 INFO - 'PAGER': '/bin/cat', 10:58:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:58:05 INFO - 'PWD': '/builds/slave/test', 10:58:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:58:05 INFO - 'SHELL': '/bin/bash', 10:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TVBnd82sb/Listeners', 10:58:05 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 10:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:58:05 INFO - 'USER': 'cltbld', 10:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:58:05 INFO - 'XPC_FLAGS': '0x0', 10:58:05 INFO - 'XPC_SERVICE_NAME': '0', 10:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:58:05 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/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:58:05 INFO - mozversion application_buildid: 20160531091142 10:58:05 INFO - mozversion application_changeset: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 10:58:05 INFO - mozversion application_display_name: Nightly 10:58:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:58:05 INFO - mozversion application_name: Firefox 10:58:05 INFO - mozversion application_remotingname: firefox 10:58:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:58:05 INFO - mozversion application_vendor: Mozilla 10:58:05 INFO - mozversion application_version: 49.0a1 10:58:05 INFO - mozversion platform_buildid: 20160531091142 10:58:05 INFO - mozversion platform_changeset: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 10:58:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:58:05 INFO - mozversion platform_version: 49.0a1 10:58:05 INFO - using testdate: 1464717485 10:58:05 INFO - actual date: 1464717485 10:58:05 INFO - starting webserver on 'localhost:49270' 10:58:05 INFO - SUITE-START | Running 2 tests 10:58:05 INFO - TEST-START | dromaeo_css 10:58:05 INFO - operating with platform_type : mac_ 10:58:05 INFO - Initialising browser for dromaeo_css test... 10:58:05 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpmnifHq/profile http://localhost:49270/getInfo.html) 10:58:07 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 10:58:07 INFO - PROCESS | 1661 | colorDepth:24 10:58:07 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 10:58:07 INFO - PROCESS | 1661 | __metrics 10:58:07 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:58:07 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:58:08 INFO - TEST-INFO | 1661: exit 0 10:58:08 INFO - Browser initialized. 10:58:08 INFO - Running cycle 1/1 for dromaeo_css test... 10:58:08 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpmnifHq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:58:20 INFO - PROCESS | 1664 | RSS: Main: 241643520 10:58:20 INFO - PROCESS | 1664 | 10:59:12 INFO - PROCESS | 1664 | 1464717552098 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] 11:01:23 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 11:01:24 INFO - PROCESS | 1664 | RSS: Main: 374902784 11:01:24 INFO - PROCESS | 1664 | 11:04:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 11:04:28 INFO - PROCESS | 1664 | RSS: Main: 380301312 11:04:28 INFO - PROCESS | 1664 | 11:07:30 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 11:07:31 INFO - PROCESS | 1664 | RSS: Main: 378392576 11:07:31 INFO - PROCESS | 1664 | 11:10:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 11:10:34 INFO - PROCESS | 1664 | RSS: Main: 367517696 11:10:34 INFO - PROCESS | 1664 | 11:13:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 11:13:38 INFO - PROCESS | 1664 | RSS: Main: 335982592 11:13:38 INFO - PROCESS | 1664 | 11:16:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 11:16:42 INFO - PROCESS | 1664 | RSS: Main: 381448192 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | __start_tp_report 11:16:42 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 11:16:42 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 11:16:42 INFO - PROCESS | 1664 | |i|pagename|runs| 11:16:42 INFO - PROCESS | 1664 | |0;dojo.html;3327.0702992264505;3334.9833250833744;3347.2301370684736;3351.66483351665;3401.268727223648;5335.973320133398;5357.892842143155;5402.810901618444;5418;5491.505764481196;4565.703229290094;5511;5557.694326812024;5570.86072848179;5572.554195664347;5019.498050194969;5036.294918711384;5069.746512674352;5123.897522049557;5142.202958541413;4262.339337402692;5061.114304996611;5110.259012443194;5139.126348520742;5168.638195326329;5024.346834911456;5068.771905264253;5070.264811602316;5085.872853178663;5150.995555866608;5094.61790365724;5137.922931156036;5139.614528910347;5149.742512874379;5189.117849965515;4945.23348880924;4974.651774375795;5003.099442100457;5065.493450654942;5068.442471328188;5431.755570999275;5496.422875598085;5500;5511.614187006911;5538.972305138489;6120.663363515005;6127.080937859312;6127.172831667757;6127.406874212692;6144.201253836973;4915.901681966377;5290.709011004393;6102.633841969496;6207.037909124093;6218.067289906505;5640.435956404368;5689.9146512802345;5710.457506536873;5710.486056254957;5711.543076553949;6234.750609975641;6248.437640612276;6284.780032698834;6297.370262973757;6310.779122730774;5797.681127538026;5839.270091238595;5858.8535286617325;5976.3378484785435;6068.605540639845;6051.220253063697;6091.950029531589;6101.786437474756;6125.669090037545;6138.112525404833;6087.269527656709;6090.969545152246;6094.603850749687;6111.266648002179;6125.019996800491;6083.239595050619;6086.269647642312;6095.542834287447;6119.84700382494;6130.724117414727;4720.740359280204;4744.4069491313585;4783.210770222874;4818.855434336975;4835.153848076572;4344.9131017380105;4385.166818304512;4397.142557201315;4405.84579539721;4416.646668266531;5469.124940009579;5572.359178694502;5592.804251851166;5596.440355964371;5607.102863541813;6112.138751317739;6140.219336392396;6165.445109940127;6171.290301615371;6192.380761923771;5595.048841696921;5597.972010139923;5600.551955843523;5612.803551875746;5650.434956504317;5175.715335656575;5217;5224.0074385866565;5271.235670827708;5276.445973172838;6127.111568822495;6162.938370616236;6166.753329866845;6221.4089661482085;6284.654344011032;6121.112438696453;6162.075688646739;6165.475934545614;6189.938100619116;6203.31763506023;6286.874262514815;6289.433950944437;6301.40136687014;6319.652419116993;6334.714937827901;5809.651420914759;5907.2911250650195;5976.103584462192;6050.2437195350585;6077.453029227391;6157.918567796436;6159.692015399122;6168.414000669929;6171.290301615371;6175.197224360804;5916.645001299936;5925.229720136354;5962.940370596412;5984.58107932458;6006.81979540597;5724.8568785779025;5809.593328466966;5852.94147058524;5913.793017244376;5964.850878728066;5562.694051827189;5819.098039803837;5831.183634291117;5852.473277405054;5878.470937615635;5981.551383646331;5983.222181116427;5987.43119403656;5988.760449581968;6037.577369584087;5696.088625819849;5717.256756621613;5775.364709881826;5789.739461724148;5804.477597016289 11:16:42 INFO - PROCESS | 1664 | |1;ext.html;476.8640937332861;509.41162956784905;510.0105794758171;515.40728162613;558.2324304081887;31099.378012439767;32718.67281327186;32728;32834.67165328346;33048.50427243587;33700.49449258263;42331.57668423315;43345;43441.6967490975;46101;9547.522623868814;9753.95123024384;9754.41473511588;9770.804583908353;9799.412035277906;8598.957005214996;9477.573509192103;9629.085236902483;9637.22902167825;9649.324547281693;8805.383623146383;11528;11767.352795596238;11803.583569971615;12038.277703337828;11866.110041746904;11964.461599228056;11970.940145299304;11998.880011199864;12026.819597706042;8519.446235994641;9864.408135511892;11678.124140689397;11751.47118379675;11880.58417955376;40878.38682419766;41355;41438.1712365754;41501;41593.58406415958;3519.7008254298416;3543.007957771828;3553.1650062235353;3594.2272411431404;3694.8706795262037;30504.38991220163;31354.52968205479;31542.842285788425;31849;32353;3251.7886337387995;3254.9674503255137;3273.2798784267425;3275.3234718229437;3286;363418;363459.9999999974;363490;363611;364367;57651;58624.706876465345;58760;59148.11277830836;60914;63355.04967425492;63376;63403;63538.0469292961;63610.0458493123;16220.51338459848;16644.833551664327;16646.75029874553;16652.916735416246;16679.24943377551;12894.548690795777;12982.35088245584;13000.739985200433;13009.284489353176;13014;14339.641508962359;14377.712445751236;14581.343839527246;16123.758143627854;16163.27265273042;7313.049303590606;7319.194888562361;7320.1581818090535;7405.888911666221;7420.295071968154;747.8579069976687;856.3277826905849;1045.3153184664056;1058.5871510111062;1120.6133883810073;28768;30190.849045754632;30330.84834575813;30888.845555772077;31396.215094622818;1059.5761695321778;1089.2484185911696;1107.9390633515234;1148.8793676663997;1169.2049406403728;15596.376144954074;15630.45293414725;15921.203939802963;16013.119278439564;16231.51305460838;647753;651275;654327;654338;657233;50646.49353506492;59503.107453388235;59644.70177649084;61934.0709889352;63313;1406.9577912662637;1419.921904295253;1437.5328018394273;1438.173050495969;1440.848710885342;30588.694113058584;31234.21914452157;31304;32009.35981280408;32190.19524511906;50647.240291396396;50959.72600685013;51375.743121284155;51384.74307628438;51388;558847;751293;778084;798322;798716;39006.804965976124;39449.80275098606;39562.40656390214;39800;39845.60154398419;46708.59874203779;46757;47221.05557888892;47759.761201193774;47793;37204.813975929945;38451;38474.0381490465;38930.416043759935;39461;37697;38199.427008594335;38524;38819.02952426212;39158.216835662555;36718.531258750416;37052;37114.257714845015;37813.243735125696;37984.151188660406;33542.664573353955;33774.155646109044;34394.82802585971;34476.13809654779;34567.4814877772;21429.89285053565;23613.84912576128;24882.377940551632;25203.369915752253;25232.87383563144 11:16:42 INFO - PROCESS | 1664 | |2;jquery.html;383.0767849482747;383.9021049632343;389.2565200467108;400.7054814711185;409.2531130686496;19624.411267661973;21061.946902654843;21132.683009754866;21604;21617.67573486399;20409.285675001378;20530.38408847733;21348.7190768554;21591.1363545458;21713.240036598723;5873.148393482953;5974.133750606159;6147.4159954804445;6149.200603921484;6178.8455288617915;4230.407742915994;4444.888877778049;5595.972020139893;6067.787627433041;6811.591304521745;6385.425311721944;6751.122354093961;6760.628165450898;6760.695768690421;6771.221309549415;6630.801075967729;6749.358810912955;6782.287859774727;6787.981802729605;6823.1812182537915;3786.4320351947154;3920.2551515212017;5476.507114359687;5816.214811000503;5912.438318359749;25884.35289117768;26218.86890565535;26269.34326641849;26343;26450.206493805217;1961.7645882494048;1982.8017198280202;1991.8207361337404;2129.9254526091668;2481.491294284667;15137.788976881822;15506.5348039559;15742.212889355487;16000.279987400594;16388.26252818626;1651.6779228050534;1858.6282743451363;1941.0197850085758;1980.6137803128395;2031.2687432524278;97549.56126096833;101177;111193;117853.41073294665;118487.4075629625;9312.022237665084;10627.893721062845;10737.892621073846;10968.341899486057;11125.554977800797;13904.652383690489;13949;13963.860361396459;13981.510647127301;13985.650358741113;7189.712411503586;8322.583870806435;8745.606447709868;8752.343574231958;8850.114988501098;7536.321731044232;8606.612702428281;8825.161609647073;8833.514156721314;9008.41445306053;5597.692126932976;6598.7030583623855;6730.93269067303;6734.259231484532;6743.15710536183;1508.4117194294233;1652.512508809904;1713.6144367517459;1806.756087928113;1824.6715591193713;897.7665806890255;960.2366118935463;1012.6860673191334;1036.326387847905;1102.9062529684907;21262.893685531475;22024.559508810056;22040.008199631055;22107.557848842935;22206.555868882853;988.3871999360442;990.9891910251503;992.3748038735695;1003.94478303694;1012.5342342522522;9677.564509597085;10403.84394234087;11637.650870473899;11826.467808948619;11859.525618975316;515869.8413015822;579138;594570.4072194602;596335;599852;91269.54365228131;107653.46173269083;109761;115465;116617;1045.8640376750973;1046.6179844356907;1085.6905781852133;1119.0376276402287;1121.315997241682;18773.6245275091;18967.33614323492;19400.320988765325;19419.70870436973;19459.902700486407;33440.498392523645;42531.66075865733;44396.334054988554;46490;49957;69763;70708.29291707017;71509.56980860457;72456.55086898338;73810;7626.923730762622;7858.685652573833;8085.312748416451;8298.200819428603;8317.293030092509;12953.805692914802;13036.217826930415;13329.600111996655;13374.398152083375;13441.932790335986;11089;11244;11269.605563805228;11336.093112551142;11482.770344592895;10987.285826421257;11135.777284454427;11156.44217789091;11243.943780281374;11279.15406344511;11481.598144064918;11543.769124617293;11692.298462092302;11738.530458781554;11750.58872939443;7978.601069946596;8105.716299929474;8130.308923741548;8335.208155225244;8380.706675266336;11545.653630391102;11651.88348116542;11752.059835213333;11753.764924701629;11759.529618815151 11:16:42 INFO - PROCESS | 1664 | |3;mootools.html;1391.6590435343342;1541.2833032639821;1567.8197007344156;1789.6420715856823;1955.511122219445;7854.5287282763;8561.529115898624;8787.209151176392;8793.98869130051;8931.598078086485;8527.360447966395;8730.519821409836;8737.21365077143;8751.868721969175;8766.6931657392;2071.399294204679;2158.84888057836;2295.8163346932206;2336.2991102669216;2379.262428647125;2272.7272727272752;2304.44693273614;2310.260716570698;2310.5378924215224;2311.1448763957274;1911.569896773222;2259.0737797503025;2288.7139107611547;2302.4128847143957;2307.5500277445904;2054.7123402723632;2074.5539708962647;2203.898050974513;2255.537614788964;2324.8256380771427;2085.5307555799977;2106.7155933949025;2107.8313734901326;2110.6517424624917;2111.356036408895;7064.752733654297;8510.48937063778;8560.186782255676;8561.443506172141;8568.742937711817;4720.12677654635;5352.357717073937;5356.491133342326;5356.946430535722;5365.946340536584;3439.5872495300473;3475.791452512832;3633.5458067741533;3648.452732090181;3654.524911761454;976.9364991275544;1030.9329893556894;1085.0993675249604;1101.6419663609358;1117.251441534174;11761;11766.411679415995;11796.997255233315;11806.822897656542;11824.408779560987;4663.463701674283;4665.510121437268;4783.306420569068;4798.688085274446;4798.7840547175465;9975.952524984772;9985.251106167067;9987.700368988943;9989.300748947504;9991.25065620067;5368.731563421813;5372.274742909735;5373.194020896819;5382.165764306539;5403.729813509309;4369.366441865912;4532.546745325507;4755.096531658974;4902.240152776325;4904.509549045139;3873.86441474547;3931.4299426583557;3991.5808840071954;4025.496812898388;4044.4135600337786;1259.4458438287124;1265.1207410849556;1271.7456508698297;1273.2678709741936;1276.1003492537739;787.7046107709609;814.3566582399837;849.838530679169;861.4615865084322;927.3832901120815;4477.485089214717;5737.540996720252;6083.574149809471;6129.632222066691;6219.036049412348;420.0737374090124;432.7446806384248;438.3556172426528;444.8087322451377;450.48419559604724;3499.331053961637;3900.941485877714;4031.496062992126;4133.491275684353;4146.274401979642;10002.549885255035;10543.261971662063;10719.83920241197;10732.463376831127;10781.730456738487;10571.471426428649;10677.893221067845;10680;10683.14534837211;10699.411532365639;7804.3366313862825;8135.7152499662225;8154.551499667576;8154.75535733929;8264.876026859602;6000.129981152621;6058.394160583906;6110.175126358063;6179.134921111138;6270.059491076375;9665.806683866424;9931.65239216624;10147.898521014695;10163.898361016296;10188.541515631667;10123.544440500047;10266;10266.794664106526;10266.794664106825;10301.484925753532;6084.726187321493;6994.300569943169;7224.89162662571;7534.284242996907;7661.118971318147;6945.652717364213;7892.723754668559;8486.660533578588;8606.096359882122;8609.440386374865;7470.738524151846;7542.2834830691;7615.809604759926;7626.34284894296;7723.1118421380015;7362.6686799093095;7438.702451901863;7455.366293864865;7535.246475352421;7650.73222437212;7075.044868942627;7090.07828982229;7132.037174981311;7141.571505709674;7155.856882862209;4941.209406494943;5081.796728130981;5167.92248116271;5170.767315470737;5180.274761533313;4443.400140980926;5106.897862042664;5127.2052831811125;5155.348911460202;5255.63210575256 11:16:42 INFO - PROCESS | 1664 | |4;prototype.html;296.55516724912616;306.14280015955325;314.6224530563324;316.40673736743605;317.9411808815369;4470.82116715331;5323.281357016802;5348.411674715778;5350.725941474868;5372.22102795095;4010.5187377514667;4314.805833737481;5133.871653208672;5259.6055295852775;5308.787648494065;3288.8848890288846;3703.851805940167;4032.8386864525382;4034.69739769517;4052.9797351013276;3630.2376500934834;3980.7810570418615;4005.379166229239;4036.313826649462;4041.919161616766;3566.6076731559506;3665.8167091645446;3807.4669546263544;3946.171304026158;4010.9197816043807;3546.804925729084;3968.980155099206;4025.879223623296;4035.273650742865;4080.469538959946;3293;3342.9331413371838;3502;3533.2580158166816;3533.6643018913164;4252.9367658085475;4819.44576373718;5051.898962020776;5057.797688092472;5157.974210128963;4553.929826490772;4577.9542204577865;4591.4260717410325;4591.841834337856;4629.976850115761;4394.4067550880545;5146.768395422215;5325.946740532584;5448.182772584105;5453.072977593819;3246.96753032469;3288.5067239913965;3848.749831260959;3890.6692931100893;3901.902452438683;4824.469308376076;4935.358403407606;5841.6495010299095;5862.267216597926;5912.379200183962;3742.214135031674;3781.8865434037025;3784.243151369737;3792.355299599075;3802.2015376770637;3370.5618269624792;3869.613038696164;3870.7677539347164;3872.7482713623526;3893.824777885002;4399.463793709005;4411.7794110295;4493.573110554492;4511.887202819956;4535.841245556389;4915.582175515121;5478.45215478457;5520.641158324696;5544.528715059265;5549.528290095387;1127.5827943660897;1248.6878280429892;1314.8159257703928;1321.8612045735251;1327.1486276644027;735.724103461202;822.1901427094307;867.3408209760502;884.9115587827009;890.5547226386807;5143.8456846294675;5150.480608220584;5152.407473140562;5179.161397703836;5241.161414173714;4765.11845308619;5090.363704536933;5097.8725531862;5692.438402718793;5793.304803423616;4918.704877707349;5066.594672426197;5087.643864929493;5124.410692770304;5128.333266701295;2645.790764711449;2731.4400547887635;2846.4307138572367;2913.7960342776228;2967.4361871244396;4930.827421040318;5207.921881171786;5876.353601103875;5880.117982302689;5953.166556682068;5244.396894357122;5338.946610533845;5641.348566173891;5780.739866706008;5942;5828.737706803204;5853.590248682552;5856.531477481876;5857.589968702235;5865.4134586541;5288.012273065706;5568.860778480408;5595.272614560081;5636.492715655447;5646;5538.390777014444;5559.44405559457;5561.6663000220115;5593.524550413098;5601.831945041656;5812.82561523155;5819.77784665225;5844.334364706208;5871.504610540418;5931.940680593311;3192.4413227685527;3224.8548815302897;3510.7717998330027;3605.6839253672747;3791.090138366717;5127.512886275797;5562.499375056264;5579;5596.804111856066;5624.072028115315;4515.68390212682;4735.360726301905;4856.737248315393;5374.355077390738;5537.695426751407;5275.973620131875;5475.589330800286;5481.123020316731;5504.724763761876;5532.391436941853;4533.478649955166;4634.026854360487;5148.9227661584355;5148.9742551287;5357.303550538561;4228.70399072062;4836;5496.532794712494;5496.587755918242;5501.532369748456;5105.28526006352;5370.355557332989;5454.263674403905;5472.753726082416;5490.737130459936 11:16:42 INFO - PROCESS | 1664 | |5;yui.html;689.6413864790309;741.4587351233599;811.3306522119253;825.0923983618018;847.910969348218;14669.706605867876;16303.673926521462;16878.324867005304;16916.407925722604;16930.23813928373;15279.465218717347;15302.387904483836;15306.464273750393;15459.768103478456;15597;705.3087973785694;729.6680010595193;902.0798785239068;909.0999910089031;909.2634965677822;869.2394155114275;906.5965645287849;907.9092090790901;911.4667919267237;912.0059135542258;938.9859152112724;951.4529145741192;952.5332587032374;952.5570609666504;954.0030667952007;735.2427000189813;916.1754421021134;940.9435433873921;947.6020071569957;949.8195342884865;699.9755008574675;700.425650966208;700.8002719225047;700.85982803439;701.0920857489564;14885.70228595422;14957.775633365509;14974.251287435585;14984.475543356039;14989.775153372599;11140;13624.250666213355;13633.795493067611;13672.445164257364;13749.243791591458;11320.26418282809;11545.804388025423;11565.174129353234;11787.292762434196;11968.581099661556;3324.3185147045037;3728.3102626014;3885.2229554089295;3894.7468414552964;4047.5750046245016;82439.5878020606;83419.16580834174;84253.15746842575;84389.73415398774;84480.99999999939;11010.284331518425;11059;11213.55145794159;11253.887461125447;11458.541658333574;21759.347219583437;21762.673559896615;21766.45583860416;21777.891110544348;21797;7884.519661523146;7928.5639289838455;8086.353091752646;8191.385646076568;8204.302634276153;4270.35944608305;4397.73613583186;4467.374567560544;4484.170428470743;4532.88667783308;3430.2967891582666;3519.8768043118366;3520.15516276097;3524.7004004659893;3525.7179425645886;580.6458060583041;596.764278110144;600.3996003996004;610.2737742086903;612.6293592376637;500.8597592674057;594.7739858853695;637.1844039629282;684.3259389501337;688.3391943733972;9066.727998159933;9462.195713364441;9472.289578281656;9510.524473776284;9525.523723813782;587.3568442555395;639.8720255948735;677.678102901118;681.6182937554985;701.929807019294;4074.8777536673947;4260.339647354665;4409.625181859514;4459.375687403766;4553.931691024637;76192.61903690446;78960;79056;79587.20412795914;92687.53656231676;68334.65832670804;69081.65459172672;70365;70423.59152816916;71973;67207.3279267201;68854.65572672104;71780;74470.25529744741;77044;9137.908620913706;9531.38046027006;9638.373505722106;9639.421634701941;9788.951055244963;45818.312725309814;46295;46450.535494644624;47176.52823471721;47250.76374618105;60065.69967150311;60152;61244;63310.41723956938;63567.6821615889;13302.401391937192;13385.732285354434;13737.381817818423;13840.93079534596;14000.719985600435;14227.573172804832;14463.27683615836;14481.710365792414;14621.707565848836;14957.40170393172;13445.865541344461;13518.25649589282;13650.385732641857;13934.58196254114;14026.158430494203;13323.600291991255;13386.799198011842;13403;13639.045266831226;13642.795358069437;13027.413766380347;13643.931780341034;13661.590152295446;13676.794848077487;13682.110662806886;10875.347479151276;10941.288816227237;11242.381669007957;11501.137414693763;11695.941520292685;10788.352698837782;10970.502049231463;11407.543698251977;11442.713932151762;11498.367589782642 11:16:42 INFO - PROCESS | 1664 | __end_tp_report 11:16:42 INFO - PROCESS | 1664 | __start_cc_report 11:16:42 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1218 11:16:42 INFO - PROCESS | 1664 | __end_cc_report 11:16:42 INFO - PROCESS | 1664 | __startTimestamp1464718602068__endTimestamp 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | ------- Summary: start ------- 11:16:42 INFO - PROCESS | 1664 | Number of tests: 6 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5600.43 Median:5789.74 stddev:642.92 (11.1%) stddev-sans-first:619.67 11:16:42 INFO - PROCESS | 1664 | Values: 3327.1 3335.0 3347.2 3351.7 3401.3 5336.0 5357.9 5402.8 5418.0 5491.5 4565.7 5511.0 5557.7 5570.9 5572.6 5019.5 5036.3 5069.7 5123.9 5142.2 4262.3 5061.1 5110.3 5139.1 5168.6 5024.3 5068.8 5070.3 5085.9 5151.0 5094.6 5137.9 5139.6 5149.7 5189.1 4945.2 4974.7 5003.1 5065.5 5068.4 5431.8 5496.4 5500.0 5511.6 5539.0 6120.7 6127.1 6127.2 6127.4 6144.2 4915.9 5290.7 6102.6 6207.0 6218.1 5640.4 5689.9 5710.5 5710.5 5711.5 6234.8 6248.4 6284.8 6297.4 6310.8 5797.7 5839.3 5858.9 5976.3 6068.6 6051.2 6092.0 6101.8 6125.7 6138.1 6087.3 6091.0 6094.6 6111.3 6125.0 6083.2 6086.3 6095.5 6119.8 6130.7 4720.7 4744.4 4783.2 4818.9 4835.2 4344.9 4385.2 4397.1 4405.8 4416.6 5469.1 5572.4 5592.8 5596.4 5607.1 6112.1 6140.2 6165.4 6171.3 6192.4 5595.0 5598.0 5600.6 5612.8 5650.4 5175.7 5217.0 5224.0 5271.2 5276.4 6127.1 6162.9 6166.8 6221.4 6284.7 6121.1 6162.1 6165.5 6189.9 6203.3 6286.9 6289.4 6301.4 6319.7 6334.7 5809.7 5907.3 5976.1 6050.2 6077.5 6157.9 6159.7 6168.4 6171.3 6175.2 5916.6 5925.2 5962.9 5984.6 6006.8 5724.9 5809.6 5852.9 5913.8 5964.9 5562.7 5819.1 5831.2 5852.5 5878.5 5981.6 5983.2 5987.4 5988.8 6037.6 5696.1 5717.3 5775.4 5789.7 5804.5 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:71843.90 Median:20206.70 stddev:163487.45 (809.1%) stddev-sans-first:163858.26 11:16:42 INFO - PROCESS | 1664 | Values: 476.9 509.4 510.0 515.4 558.2 31099.4 32718.7 32728.0 32834.7 33048.5 33700.5 42331.6 43345.0 43441.7 46101.0 9547.5 9754.0 9754.4 9770.8 9799.4 8599.0 9477.6 9629.1 9637.2 9649.3 8805.4 11528.0 11767.4 11803.6 12038.3 11866.1 11964.5 11970.9 11998.9 12026.8 8519.4 9864.4 11678.1 11751.5 11880.6 40878.4 41355.0 41438.2 41501.0 41593.6 3519.7 3543.0 3553.2 3594.2 3694.9 30504.4 31354.5 31542.8 31849.0 32353.0 3251.8 3255.0 3273.3 3275.3 3286.0 363418.0 363460.0 363490.0 363611.0 364367.0 57651.0 58624.7 58760.0 59148.1 60914.0 63355.0 63376.0 63403.0 63538.0 63610.0 16220.5 16644.8 16646.8 16652.9 16679.2 12894.5 12982.4 13000.7 13009.3 13014.0 14339.6 14377.7 14581.3 16123.8 16163.3 7313.0 7319.2 7320.2 7405.9 7420.3 747.9 856.3 1045.3 1058.6 1120.6 28768.0 30190.8 30330.8 30888.8 31396.2 1059.6 1089.2 1107.9 1148.9 1169.2 15596.4 15630.5 15921.2 16013.1 16231.5 647753.0 651275.0 654327.0 654338.0 657233.0 50646.5 59503.1 59644.7 61934.1 63313.0 1407.0 1419.9 1437.5 1438.2 1440.8 30588.7 31234.2 31304.0 32009.4 32190.2 50647.2 50959.7 51375.7 51384.7 51388.0 558847.0 751293.0 778084.0 798322.0 798716.0 39006.8 39449.8 39562.4 39800.0 39845.6 46708.6 46757.0 47221.1 47759.8 47793.0 37204.8 38451.0 38474.0 38930.4 39461.0 37697.0 38199.4 38524.0 38819.0 39158.2 36718.5 37052.0 37114.3 37813.2 37984.2 33542.7 33774.2 34394.8 34476.1 34567.5 21429.9 23613.8 24882.4 25203.4 25232.9 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33559.79 Median:21868.90 stddev:95821.13 (438.2%) stddev-sans-first:96057.56 11:16:42 INFO - PROCESS | 1664 | Values: 383.1 383.9 389.3 400.7 409.3 19624.4 21061.9 21132.7 21604.0 21617.7 20409.3 20530.4 21348.7 21591.1 21713.2 5873.1 5974.1 6147.4 6149.2 6178.8 4230.4 4444.9 5596.0 6067.8 6811.6 6385.4 6751.1 6760.6 6760.7 6771.2 6630.8 6749.4 6782.3 6788.0 6823.2 3786.4 3920.3 5476.5 5816.2 5912.4 25884.4 26218.9 26269.3 26343.0 26450.2 1961.8 1982.8 1991.8 2129.9 2481.5 15137.8 15506.5 15742.2 16000.3 16388.3 1651.7 1858.6 1941.0 1980.6 2031.3 97549.6 101177.0 111193.0 117853.4 118487.4 9312.0 10627.9 10737.9 10968.3 11125.6 13904.7 13949.0 13963.9 13981.5 13985.7 7189.7 8322.6 8745.6 8752.3 8850.1 7536.3 8606.6 8825.2 8833.5 9008.4 5597.7 6598.7 6730.9 6734.3 6743.2 1508.4 1652.5 1713.6 1806.8 1824.7 897.8 960.2 1012.7 1036.3 1102.9 21262.9 22024.6 22040.0 22107.6 22206.6 988.4 991.0 992.4 1003.9 1012.5 9677.6 10403.8 11637.7 11826.5 11859.5 515869.8 579138.0 594570.4 596335.0 599852.0 91269.5 107653.5 109761.0 115465.0 116617.0 1045.9 1046.6 1085.7 1119.0 1121.3 18773.6 18967.3 19400.3 19419.7 19459.9 33440.5 42531.7 44396.3 46490.0 49957.0 69763.0 70708.3 71509.6 72456.6 73810.0 7626.9 7858.7 8085.3 8298.2 8317.3 12953.8 13036.2 13329.6 13374.4 13441.9 11089.0 11244.0 11269.6 11336.1 11482.8 10987.3 11135.8 11156.4 11243.9 11279.2 11481.6 11543.8 11692.3 11738.5 11750.6 7978.6 8105.7 8130.3 8335.2 8380.7 11545.7 11651.9 11752.1 11753.8 11759.5 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5670.64 Median:4791.00 stddev:3236.12 (67.5%) stddev-sans-first:3229.22 11:16:42 INFO - PROCESS | 1664 | Values: 1391.7 1541.3 1567.8 1789.6 1955.5 7854.5 8561.5 8787.2 8794.0 8931.6 8527.4 8730.5 8737.2 8751.9 8766.7 2071.4 2158.8 2295.8 2336.3 2379.3 2272.7 2304.4 2310.3 2310.5 2311.1 1911.6 2259.1 2288.7 2302.4 2307.6 2054.7 2074.6 2203.9 2255.5 2324.8 2085.5 2106.7 2107.8 2110.7 2111.4 7064.8 8510.5 8560.2 8561.4 8568.7 4720.1 5352.4 5356.5 5356.9 5365.9 3439.6 3475.8 3633.5 3648.5 3654.5 976.9 1030.9 1085.1 1101.6 1117.3 11761.0 11766.4 11797.0 11806.8 11824.4 4663.5 4665.5 4783.3 4798.7 4798.8 9976.0 9985.3 9987.7 9989.3 9991.3 5368.7 5372.3 5373.2 5382.2 5403.7 4369.4 4532.5 4755.1 4902.2 4904.5 3873.9 3931.4 3991.6 4025.5 4044.4 1259.4 1265.1 1271.7 1273.3 1276.1 787.7 814.4 849.8 861.5 927.4 4477.5 5737.5 6083.6 6129.6 6219.0 420.1 432.7 438.4 444.8 450.5 3499.3 3900.9 4031.5 4133.5 4146.3 10002.5 10543.3 10719.8 10732.5 10781.7 10571.5 10677.9 10680.0 10683.1 10699.4 7804.3 8135.7 8154.6 8154.8 8264.9 6000.1 6058.4 6110.2 6179.1 6270.1 9665.8 9931.7 10147.9 10163.9 10188.5 10123.5 10266.0 10266.8 10266.8 10301.5 6084.7 6994.3 7224.9 7534.3 7661.1 6945.7 7892.7 8486.7 8606.1 8609.4 7470.7 7542.3 7615.8 7626.3 7723.1 7362.7 7438.7 7455.4 7535.2 7650.7 7075.0 7090.1 7132.0 7141.6 7155.9 4941.2 5081.8 5167.9 5170.8 5180.3 4443.4 5106.9 5127.2 5155.3 5255.6 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4435.81 Median:5062.20 stddev:1378.72 (27.2%) stddev-sans-first:1347.13 11:16:42 INFO - PROCESS | 1664 | Values: 296.6 306.1 314.6 316.4 317.9 4470.8 5323.3 5348.4 5350.7 5372.2 4010.5 4314.8 5133.9 5259.6 5308.8 3288.9 3703.9 4032.8 4034.7 4053.0 3630.2 3980.8 4005.4 4036.3 4041.9 3566.6 3665.8 3807.5 3946.2 4010.9 3546.8 3969.0 4025.9 4035.3 4080.5 3293.0 3342.9 3502.0 3533.3 3533.7 4252.9 4819.4 5051.9 5057.8 5158.0 4553.9 4578.0 4591.4 4591.8 4630.0 4394.4 5146.8 5325.9 5448.2 5453.1 3247.0 3288.5 3848.7 3890.7 3901.9 4824.5 4935.4 5841.6 5862.3 5912.4 3742.2 3781.9 3784.2 3792.4 3802.2 3370.6 3869.6 3870.8 3872.7 3893.8 4399.5 4411.8 4493.6 4511.9 4535.8 4915.6 5478.5 5520.6 5544.5 5549.5 1127.6 1248.7 1314.8 1321.9 1327.1 735.7 822.2 867.3 884.9 890.6 5143.8 5150.5 5152.4 5179.2 5241.2 4765.1 5090.4 5097.9 5692.4 5793.3 4918.7 5066.6 5087.6 5124.4 5128.3 2645.8 2731.4 2846.4 2913.8 2967.4 4930.8 5207.9 5876.4 5880.1 5953.2 5244.4 5338.9 5641.3 5780.7 5942.0 5828.7 5853.6 5856.5 5857.6 5865.4 5288.0 5568.9 5595.3 5636.5 5646.0 5538.4 5559.4 5561.7 5593.5 5601.8 5812.8 5819.8 5844.3 5871.5 5931.9 3192.4 3224.9 3510.8 3605.7 3791.1 5127.5 5562.5 5579.0 5596.8 5624.1 4515.7 4735.4 4856.7 5374.4 5537.7 5276.0 5475.6 5481.1 5504.7 5532.4 4533.5 4634.0 5148.9 5149.0 5357.3 4228.7 4836.0 5496.5 5496.6 5501.5 5105.3 5370.4 5454.3 5472.8 5490.7 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18365.40 Median:46056.66 stddev:24070.47 (52.3%) stddev-sans-first:24101.40 11:16:42 INFO - PROCESS | 1664 | Values: 689.6 741.5 811.3 825.1 847.9 14669.7 16303.7 16878.3 16916.4 16930.2 15279.5 15302.4 15306.5 15459.8 15597.0 705.3 729.7 902.1 909.1 909.3 869.2 906.6 907.9 911.5 912.0 939.0 951.5 952.5 952.6 954.0 735.2 916.2 940.9 947.6 949.8 700.0 700.4 700.8 700.9 701.1 14885.7 14957.8 14974.3 14984.5 14989.8 11140.0 13624.3 13633.8 13672.4 13749.2 11320.3 11545.8 11565.2 11787.3 11968.6 3324.3 3728.3 3885.2 3894.7 4047.6 82439.6 83419.2 84253.2 84389.7 84481.0 11010.3 11059.0 11213.6 11253.9 11458.5 21759.3 21762.7 21766.5 21777.9 21797.0 7884.5 7928.6 8086.4 8191.4 8204.3 4270.4 4397.7 4467.4 4484.2 4532.9 3430.3 3519.9 3520.2 3524.7 3525.7 580.6 596.8 600.4 610.3 612.6 500.9 594.8 637.2 684.3 688.3 9066.7 9462.2 9472.3 9510.5 9525.5 587.4 639.9 677.7 681.6 701.9 4074.9 4260.3 4409.6 4459.4 4553.9 76192.6 78960.0 79056.0 79587.2 92687.5 68334.7 69081.7 70365.0 70423.6 71973.0 67207.3 68854.7 71780.0 74470.3 77044.0 9137.9 9531.4 9638.4 9639.4 9789.0 45818.3 46295.0 46450.5 47176.5 47250.8 60065.7 60152.0 61244.0 63310.4 63567.7 13302.4 13385.7 13737.4 13840.9 14000.7 14227.6 14463.3 14481.7 14621.7 14957.4 13445.9 13518.3 13650.4 13934.6 14026.2 13323.6 13386.8 13403.0 13639.0 13642.8 13027.4 13643.9 13661.6 13676.8 13682.1 10875.3 10941.3 11242.4 11501.1 11695.9 10788.4 10970.5 11407.5 11442.7 11498.4 11:16:42 INFO - PROCESS | 1664 | -------- Summary: end -------- 11:16:42 INFO - PROCESS | 1664 | 11:16:42 INFO - TEST-INFO | 1664: exit 0 11:16:42 INFO - TEST-OK | dromaeo_css | took 1117265ms 11:16:42 INFO - TEST-START | kraken 11:16:42 INFO - operating with platform_type : mac_ 11:16:42 INFO - Initialising browser for kraken test... 11:16:42 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpB8u2Ui/profile http://localhost:49270/getInfo.html) 11:16:44 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 11:16:44 INFO - PROCESS | 1670 | colorDepth:24 11:16:44 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 11:16:44 INFO - PROCESS | 1670 | __metrics 11:16:45 INFO - PROCESS | 1670 | 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" 11:16:45 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:16:45 INFO - TEST-INFO | 1670: exit 0 11:16:45 INFO - Browser initialized. 11:16:45 INFO - Running cycle 1/1 for kraken test... 11:16:45 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpB8u2Ui/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:57 INFO - PROCESS | 1671 | RSS: Main: 241917952 11:16:57 INFO - PROCESS | 1671 | 11:17:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 11:17:01 INFO - PROCESS | 1671 | RSS: Main: 291844096 11:17:01 INFO - PROCESS | 1671 | 11:17:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 11:17:04 INFO - PROCESS | 1671 | RSS: Main: 301244416 11:17:04 INFO - PROCESS | 1671 | 11:17:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 11:17:08 INFO - PROCESS | 1671 | RSS: Main: 387022848 11:17:08 INFO - PROCESS | 1671 | 11:17:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 11:17:11 INFO - PROCESS | 1671 | RSS: Main: 286945280 11:17:11 INFO - PROCESS | 1671 | 11:17:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:17:14 INFO - PROCESS | 1671 | RSS: Main: 290545664 11:17:14 INFO - PROCESS | 1671 | 11:17:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 11:17:17 INFO - PROCESS | 1671 | RSS: Main: 326721536 11:17:17 INFO - PROCESS | 1671 | 11:17:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 11:17:20 INFO - PROCESS | 1671 | RSS: Main: 329351168 11:17:20 INFO - PROCESS | 1671 | 11:17:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 11:17:24 INFO - PROCESS | 1671 | RSS: Main: 326885376 11:17:24 INFO - PROCESS | 1671 | 11:17:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:17:26 INFO - PROCESS | 1671 | RSS: Main: 309440512 11:17:26 INFO - PROCESS | 1671 | 11:17:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:17:29 INFO - PROCESS | 1671 | RSS: Main: 316420096 11:17:29 INFO - PROCESS | 1671 | 11:17:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:17:31 INFO - PROCESS | 1671 | RSS: Main: 320724992 11:17:31 INFO - PROCESS | 1671 | 11:17:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:17:35 INFO - PROCESS | 1671 | RSS: Main: 323559424 11:17:35 INFO - PROCESS | 1671 | 11:17:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:17:39 INFO - PROCESS | 1671 | RSS: Main: 323252224 11:17:39 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 11:17:40 INFO - PROCESS | 1671 | RSS: Main: 367124480 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | __start_tp_report 11:17:40 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 11:17:40 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 11:17:40 INFO - PROCESS | 1671 | |i|pagename|runs| 11:17:40 INFO - PROCESS | 1671 | |0;ai-astar;112;106;112;111;113;110;114;111;111;111 11:17:40 INFO - PROCESS | 1671 | |1;audio-beat-detection;154;117;117;169;117;120;139;116;144;117 11:17:40 INFO - PROCESS | 1671 | |2;audio-dft;186;189;186;183;186;184;182;183;177;185 11:17:40 INFO - PROCESS | 1671 | |3;audio-fft;84;90;75;132;81;47;68;90;72;70 11:17:40 INFO - PROCESS | 1671 | |4;audio-oscillator;83;94;93;71;71;68;98;77;97;135 11:17:40 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;87;87;86;88;85;89;85;88;85 11:17:40 INFO - PROCESS | 1671 | |6;imaging-darkroom;94;92;94;94;94;94;93;94;101;92 11:17:40 INFO - PROCESS | 1671 | |7;imaging-desaturate;87;84;86;87;84;86;84;84;84;85 11:17:40 INFO - PROCESS | 1671 | |8;json-parse-financial;56;57;57;56;56;55;55;55;54;55 11:17:40 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;66;66;76;78;72;69;70;69;70;72 11:17:40 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;87;86;88;86;86;86;86;89;143;87 11:17:40 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;138;145;145;138;137;137;142;138;155;141 11:17:40 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;166;168;165;166;170;222;222;168;164;173 11:17:40 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;66;67;66;66;66;67;66;64;65 11:17:40 INFO - PROCESS | 1671 | __end_tp_report 11:17:40 INFO - PROCESS | 1671 | __start_cc_report 11:17:40 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1951 11:17:40 INFO - PROCESS | 1671 | __end_cc_report 11:17:40 INFO - PROCESS | 1671 | __startTimestamp1464718660969__endTimestamp 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | ------- Summary: start ------- 11:17:40 INFO - PROCESS | 1671 | Number of tests: 14 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:2.13 (1.9%) stddev-sans-first:2.24 11:17:40 INFO - PROCESS | 1671 | Values: 112.0 106.0 112.0 111.0 113.0 110.0 114.0 111.0 111.0 111.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:129.50 stddev:19.25 (14.9%) stddev-sans-first:18.53 11:17:40 INFO - PROCESS | 1671 | Values: 154.0 117.0 117.0 169.0 117.0 120.0 139.0 116.0 144.0 117.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.50 stddev:3.21 (1.7%) stddev-sans-first:3.33 11:17:40 INFO - PROCESS | 1671 | Values: 186.0 189.0 186.0 183.0 186.0 184.0 182.0 183.0 177.0 185.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:80.90 Median:78.00 stddev:21.95 (28.1%) stddev-sans-first:23.25 11:17:40 INFO - PROCESS | 1671 | Values: 84.0 90.0 75.0 132.0 81.0 47.0 68.0 90.0 72.0 70.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:88.00 stddev:19.92 (22.6%) stddev-sans-first:21.02 11:17:40 INFO - PROCESS | 1671 | Values: 83.0 94.0 93.0 71.0 71.0 68.0 98.0 77.0 97.0 135.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:1.48 (1.7%) stddev-sans-first:1.50 11:17:40 INFO - PROCESS | 1671 | Values: 88.0 87.0 87.0 86.0 88.0 85.0 89.0 85.0 88.0 85.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:2.53 (2.7%) stddev-sans-first:2.68 11:17:40 INFO - PROCESS | 1671 | Values: 94.0 92.0 94.0 94.0 94.0 94.0 93.0 94.0 101.0 92.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.17 11:17:40 INFO - PROCESS | 1671 | Values: 87.0 84.0 86.0 87.0 84.0 86.0 84.0 84.0 84.0 85.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 11:17:40 INFO - PROCESS | 1671 | Values: 56.0 57.0 57.0 56.0 56.0 55.0 55.0 55.0 54.0 55.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.88 (5.5%) stddev-sans-first:3.71 11:17:40 INFO - PROCESS | 1671 | Values: 66.0 66.0 76.0 78.0 72.0 69.0 70.0 69.0 70.0 72.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:86.50 stddev:17.81 (20.6%) stddev-sans-first:18.78 11:17:40 INFO - PROCESS | 1671 | Values: 87.0 86.0 88.0 86.0 86.0 86.0 86.0 89.0 143.0 87.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:141.50 stddev:5.62 (4.0%) stddev-sans-first:5.81 11:17:40 INFO - PROCESS | 1671 | Values: 138.0 145.0 145.0 138.0 137.0 137.0 142.0 138.0 155.0 141.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:169.00 stddev:23.12 (13.7%) stddev-sans-first:24.09 11:17:40 INFO - PROCESS | 1671 | Values: 166.0 168.0 165.0 166.0 170.0 222.0 222.0 168.0 164.0 173.0 11:17:40 INFO - PROCESS | 1671 | 11:17:40 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:0.93 11:17:40 INFO - PROCESS | 1671 | Values: 67.0 66.0 67.0 66.0 66.0 66.0 67.0 66.0 64.0 65.0 11:17:40 INFO - PROCESS | 1671 | -------- Summary: end -------- 11:17:40 INFO - PROCESS | 1671 | 11:17:41 INFO - TEST-INFO | 1671: exit 0 11:17:41 INFO - TEST-OK | kraken | took 59246ms 11:17:41 INFO - SUITE-END | took 1176s 11:17:42 INFO - Completed test suite (00:19:36) 11:17:42 INFO - javascript benchmark 11:17:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.0702992264505, 3334.9833250833744, 3347.2301370684736, 3351.66483351665, 3401.268727223648, 5335.973320133398, 5357.892842143155, 5402.810901618444, 5418.0, 5491.505764481196, 4565.703229290094, 5511.0, 5557.694326812024, 5570.86072848179, 5572.554195664347, 5019.498050194969, 5036.294918711384, 5069.746512674352, 5123.897522049557, 5142.202958541413, 4262.339337402692, 5061.114304996611, 5110.259012443194, 5139.126348520742, 5168.638195326329, 5024.346834911456, 5068.771905264253, 5070.264811602316, 5085.872853178663, 5150.995555866608, 5094.61790365724, 5137.922931156036, 5139.614528910347, 5149.742512874379, 5189.117849965515, 4945.23348880924, 4974.651774375795, 5003.099442100457, 5065.493450654942, 5068.442471328188, 5431.755570999275, 5496.422875598085, 5500.0, 5511.614187006911, 5538.972305138489, 6120.663363515005, 6127.080937859312, 6127.172831667757, 6127.406874212692, 6144.201253836973, 4915.901681966377, 5290.709011004393, 6102.633841969496, 6207.037909124093, 6218.067289906505, 5640.435956404368, 5689.9146512802345, 5710.457506536873, 5710.486056254957, 5711.543076553949, 6234.750609975641, 6248.437640612276, 6284.780032698834, 6297.370262973757, 6310.779122730774, 5797.681127538026, 5839.270091238595, 5858.8535286617325, 5976.3378484785435, 6068.605540639845, 6051.220253063697, 6091.950029531589, 6101.786437474756, 6125.669090037545, 6138.112525404833, 6087.269527656709, 6090.969545152246, 6094.603850749687, 6111.266648002179, 6125.019996800491, 6083.239595050619, 6086.269647642312, 6095.542834287447, 6119.84700382494, 6130.724117414727, 4720.740359280204, 4744.4069491313585, 4783.210770222874, 4818.855434336975, 4835.153848076572, 4344.9131017380105, 4385.166818304512, 4397.142557201315, 4405.84579539721, 4416.646668266531, 5469.124940009579, 5572.359178694502, 5592.804251851166, 5596.440355964371, 5607.102863541813, 6112.138751317739, 6140.219336392396, 6165.445109940127, 6171.290301615371, 6192.380761923771, 5595.048841696921, 5597.972010139923, 5600.551955843523, 5612.803551875746, 5650.434956504317, 5175.715335656575, 5217.0, 5224.0074385866565, 5271.235670827708, 5276.445973172838, 6127.111568822495, 6162.938370616236, 6166.753329866845, 6221.4089661482085, 6284.654344011032, 6121.112438696453, 6162.075688646739, 6165.475934545614, 6189.938100619116, 6203.31763506023, 6286.874262514815, 6289.433950944437, 6301.40136687014, 6319.652419116993, 6334.714937827901, 5809.651420914759, 5907.2911250650195, 5976.103584462192, 6050.2437195350585, 6077.453029227391, 6157.918567796436, 6159.692015399122, 6168.414000669929, 6171.290301615371, 6175.197224360804, 5916.645001299936, 5925.229720136354, 5962.940370596412, 5984.58107932458, 6006.81979540597, 5724.8568785779025, 5809.593328466966, 5852.94147058524, 5913.793017244376, 5964.850878728066, 5562.694051827189, 5819.098039803837, 5831.183634291117, 5852.473277405054, 5878.470937615635, 5981.551383646331, 5983.222181116427, 5987.43119403656, 5988.760449581968, 6037.577369584087, 5696.088625819849, 5717.256756621613, 5775.364709881826, 5789.739461724148, 5804.477597016289], "value": 5559.768965141908, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.8640937332861, 509.41162956784905, 510.0105794758171, 515.40728162613, 558.2324304081887, 31099.378012439767, 32718.67281327186, 32728.0, 32834.67165328346, 33048.50427243587, 33700.49449258263, 42331.57668423315, 43345.0, 43441.6967490975, 46101.0, 9547.522623868814, 9753.95123024384, 9754.41473511588, 9770.804583908353, 9799.412035277906, 8598.957005214996, 9477.573509192103, 9629.085236902483, 9637.22902167825, 9649.324547281693, 8805.383623146383, 11528.0, 11767.352795596238, 11803.583569971615, 12038.277703337828, 11866.110041746904, 11964.461599228056, 11970.940145299304, 11998.880011199864, 12026.819597706042, 8519.446235994641, 9864.408135511892, 11678.124140689397, 11751.47118379675, 11880.58417955376, 40878.38682419766, 41355.0, 41438.1712365754, 41501.0, 41593.58406415958, 3519.7008254298416, 3543.007957771828, 3553.1650062235353, 3594.2272411431404, 3694.8706795262037, 30504.38991220163, 31354.52968205479, 31542.842285788425, 31849.0, 32353.0, 3251.7886337387995, 3254.9674503255137, 3273.2798784267425, 3275.3234718229437, 3286.0, 363418.0, 363459.9999999974, 363490.0, 363611.0, 364367.0, 57651.0, 58624.706876465345, 58760.0, 59148.11277830836, 60914.0, 63355.04967425492, 63376.0, 63403.0, 63538.0469292961, 63610.0458493123, 16220.51338459848, 16644.833551664327, 16646.75029874553, 16652.916735416246, 16679.24943377551, 12894.548690795777, 12982.35088245584, 13000.739985200433, 13009.284489353176, 13014.0, 14339.641508962359, 14377.712445751236, 14581.343839527246, 16123.758143627854, 16163.27265273042, 7313.049303590606, 7319.194888562361, 7320.1581818090535, 7405.888911666221, 7420.295071968154, 747.8579069976687, 856.3277826905849, 1045.3153184664056, 1058.5871510111062, 1120.6133883810073, 28768.0, 30190.849045754632, 30330.84834575813, 30888.845555772077, 31396.215094622818, 1059.5761695321778, 1089.2484185911696, 1107.9390633515234, 1148.8793676663997, 1169.2049406403728, 15596.376144954074, 15630.45293414725, 15921.203939802963, 16013.119278439564, 16231.51305460838, 647753.0, 651275.0, 654327.0, 654338.0, 657233.0, 50646.49353506492, 59503.107453388235, 59644.70177649084, 61934.0709889352, 63313.0, 1406.9577912662637, 1419.921904295253, 1437.5328018394273, 1438.173050495969, 1440.848710885342, 30588.694113058584, 31234.21914452157, 31304.0, 32009.35981280408, 32190.19524511906, 50647.240291396396, 50959.72600685013, 51375.743121284155, 51384.74307628438, 51388.0, 558847.0, 751293.0, 778084.0, 798322.0, 798716.0, 39006.804965976124, 39449.80275098606, 39562.40656390214, 39800.0, 39845.60154398419, 46708.59874203779, 46757.0, 47221.05557888892, 47759.761201193774, 47793.0, 37204.813975929945, 38451.0, 38474.0381490465, 38930.416043759935, 39461.0, 37697.0, 38199.427008594335, 38524.0, 38819.02952426212, 39158.216835662555, 36718.531258750416, 37052.0, 37114.257714845015, 37813.243735125696, 37984.151188660406, 33542.664573353955, 33774.155646109044, 34394.82802585971, 34476.13809654779, 34567.4814877772, 21429.89285053565, 23613.84912576128, 24882.377940551632, 25203.369915752253, 25232.87383563144], "value": 20364.240037735053, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.0767849482747, 383.9021049632343, 389.2565200467108, 400.7054814711185, 409.2531130686496, 19624.411267661973, 21061.946902654843, 21132.683009754866, 21604.0, 21617.67573486399, 20409.285675001378, 20530.38408847733, 21348.7190768554, 21591.1363545458, 21713.240036598723, 5873.148393482953, 5974.133750606159, 6147.4159954804445, 6149.200603921484, 6178.8455288617915, 4230.407742915994, 4444.888877778049, 5595.972020139893, 6067.787627433041, 6811.591304521745, 6385.425311721944, 6751.122354093961, 6760.628165450898, 6760.695768690421, 6771.221309549415, 6630.801075967729, 6749.358810912955, 6782.287859774727, 6787.981802729605, 6823.1812182537915, 3786.4320351947154, 3920.2551515212017, 5476.507114359687, 5816.214811000503, 5912.438318359749, 25884.35289117768, 26218.86890565535, 26269.34326641849, 26343.0, 26450.206493805217, 1961.7645882494048, 1982.8017198280202, 1991.8207361337404, 2129.9254526091668, 2481.491294284667, 15137.788976881822, 15506.5348039559, 15742.212889355487, 16000.279987400594, 16388.26252818626, 1651.6779228050534, 1858.6282743451363, 1941.0197850085758, 1980.6137803128395, 2031.2687432524278, 97549.56126096833, 101177.0, 111193.0, 117853.41073294665, 118487.4075629625, 9312.022237665084, 10627.893721062845, 10737.892621073846, 10968.341899486057, 11125.554977800797, 13904.652383690489, 13949.0, 13963.860361396459, 13981.510647127301, 13985.650358741113, 7189.712411503586, 8322.583870806435, 8745.606447709868, 8752.343574231958, 8850.114988501098, 7536.321731044232, 8606.612702428281, 8825.161609647073, 8833.514156721314, 9008.41445306053, 5597.692126932976, 6598.7030583623855, 6730.93269067303, 6734.259231484532, 6743.15710536183, 1508.4117194294233, 1652.512508809904, 1713.6144367517459, 1806.756087928113, 1824.6715591193713, 897.7665806890255, 960.2366118935463, 1012.6860673191334, 1036.326387847905, 1102.9062529684907, 21262.893685531475, 22024.559508810056, 22040.008199631055, 22107.557848842935, 22206.555868882853, 988.3871999360442, 990.9891910251503, 992.3748038735695, 1003.94478303694, 1012.5342342522522, 9677.564509597085, 10403.84394234087, 11637.650870473899, 11826.467808948619, 11859.525618975316, 515869.8413015822, 579138.0, 594570.4072194602, 596335.0, 599852.0, 91269.54365228131, 107653.46173269083, 109761.0, 115465.0, 116617.0, 1045.8640376750973, 1046.6179844356907, 1085.6905781852133, 1119.0376276402287, 1121.315997241682, 18773.6245275091, 18967.33614323492, 19400.320988765325, 19419.70870436973, 19459.902700486407, 33440.498392523645, 42531.66075865733, 44396.334054988554, 46490.0, 49957.0, 69763.0, 70708.29291707017, 71509.56980860457, 72456.55086898338, 73810.0, 7626.923730762622, 7858.685652573833, 8085.312748416451, 8298.200819428603, 8317.293030092509, 12953.805692914802, 13036.217826930415, 13329.600111996655, 13374.398152083375, 13441.932790335986, 11089.0, 11244.0, 11269.605563805228, 11336.093112551142, 11482.770344592895, 10987.285826421257, 11135.777284454427, 11156.44217789091, 11243.943780281374, 11279.15406344511, 11481.598144064918, 11543.769124617293, 11692.298462092302, 11738.530458781554, 11750.58872939443, 7978.601069946596, 8105.716299929474, 8130.308923741548, 8335.208155225244, 8380.706675266336, 11545.653630391102, 11651.88348116542, 11752.059835213333, 11753.764924701629, 11759.529618815151], "value": 9748.130477381605, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.6590435343342, 1541.2833032639821, 1567.8197007344156, 1789.6420715856823, 1955.511122219445, 7854.5287282763, 8561.529115898624, 8787.209151176392, 8793.98869130051, 8931.598078086485, 8527.360447966395, 8730.519821409836, 8737.21365077143, 8751.868721969175, 8766.6931657392, 2071.399294204679, 2158.84888057836, 2295.8163346932206, 2336.2991102669216, 2379.262428647125, 2272.7272727272752, 2304.44693273614, 2310.260716570698, 2310.5378924215224, 2311.1448763957274, 1911.569896773222, 2259.0737797503025, 2288.7139107611547, 2302.4128847143957, 2307.5500277445904, 2054.7123402723632, 2074.5539708962647, 2203.898050974513, 2255.537614788964, 2324.8256380771427, 2085.5307555799977, 2106.7155933949025, 2107.8313734901326, 2110.6517424624917, 2111.356036408895, 7064.752733654297, 8510.48937063778, 8560.186782255676, 8561.443506172141, 8568.742937711817, 4720.12677654635, 5352.357717073937, 5356.491133342326, 5356.946430535722, 5365.946340536584, 3439.5872495300473, 3475.791452512832, 3633.5458067741533, 3648.452732090181, 3654.524911761454, 976.9364991275544, 1030.9329893556894, 1085.0993675249604, 1101.6419663609358, 1117.251441534174, 11761.0, 11766.411679415995, 11796.997255233315, 11806.822897656542, 11824.408779560987, 4663.463701674283, 4665.510121437268, 4783.306420569068, 4798.688085274446, 4798.7840547175465, 9975.952524984772, 9985.251106167067, 9987.700368988943, 9989.300748947504, 9991.25065620067, 5368.731563421813, 5372.274742909735, 5373.194020896819, 5382.165764306539, 5403.729813509309, 4369.366441865912, 4532.546745325507, 4755.096531658974, 4902.240152776325, 4904.509549045139, 3873.86441474547, 3931.4299426583557, 3991.5808840071954, 4025.496812898388, 4044.4135600337786, 1259.4458438287124, 1265.1207410849556, 1271.7456508698297, 1273.2678709741936, 1276.1003492537739, 787.7046107709609, 814.3566582399837, 849.838530679169, 861.4615865084322, 927.3832901120815, 4477.485089214717, 5737.540996720252, 6083.574149809471, 6129.632222066691, 6219.036049412348, 420.0737374090124, 432.7446806384248, 438.3556172426528, 444.8087322451377, 450.48419559604724, 3499.331053961637, 3900.941485877714, 4031.496062992126, 4133.491275684353, 4146.274401979642, 10002.549885255035, 10543.261971662063, 10719.83920241197, 10732.463376831127, 10781.730456738487, 10571.471426428649, 10677.893221067845, 10680.0, 10683.14534837211, 10699.411532365639, 7804.3366313862825, 8135.7152499662225, 8154.551499667576, 8154.75535733929, 8264.876026859602, 6000.129981152621, 6058.394160583906, 6110.175126358063, 6179.134921111138, 6270.059491076375, 9665.806683866424, 9931.65239216624, 10147.898521014695, 10163.898361016296, 10188.541515631667, 10123.544440500047, 10266.0, 10266.794664106526, 10266.794664106825, 10301.484925753532, 6084.726187321493, 6994.300569943169, 7224.89162662571, 7534.284242996907, 7661.118971318147, 6945.652717364213, 7892.723754668559, 8486.660533578588, 8606.096359882122, 8609.440386374865, 7470.738524151846, 7542.2834830691, 7615.809604759926, 7626.34284894296, 7723.1118421380015, 7362.6686799093095, 7438.702451901863, 7455.366293864865, 7535.246475352421, 7650.73222437212, 7075.044868942627, 7090.07828982229, 7132.037174981311, 7141.571505709674, 7155.856882862209, 4941.209406494943, 5081.796728130981, 5167.92248116271, 5170.767315470737, 5180.274761533313, 4443.400140980926, 5106.897862042664, 5127.2052831811125, 5155.348911460202, 5255.63210575256], "value": 4448.977651459566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.55516724912616, 306.14280015955325, 314.6224530563324, 316.40673736743605, 317.9411808815369, 4470.82116715331, 5323.281357016802, 5348.411674715778, 5350.725941474868, 5372.22102795095, 4010.5187377514667, 4314.805833737481, 5133.871653208672, 5259.6055295852775, 5308.787648494065, 3288.8848890288846, 3703.851805940167, 4032.8386864525382, 4034.69739769517, 4052.9797351013276, 3630.2376500934834, 3980.7810570418615, 4005.379166229239, 4036.313826649462, 4041.919161616766, 3566.6076731559506, 3665.8167091645446, 3807.4669546263544, 3946.171304026158, 4010.9197816043807, 3546.804925729084, 3968.980155099206, 4025.879223623296, 4035.273650742865, 4080.469538959946, 3293.0, 3342.9331413371838, 3502.0, 3533.2580158166816, 3533.6643018913164, 4252.9367658085475, 4819.44576373718, 5051.898962020776, 5057.797688092472, 5157.974210128963, 4553.929826490772, 4577.9542204577865, 4591.4260717410325, 4591.841834337856, 4629.976850115761, 4394.4067550880545, 5146.768395422215, 5325.946740532584, 5448.182772584105, 5453.072977593819, 3246.96753032469, 3288.5067239913965, 3848.749831260959, 3890.6692931100893, 3901.902452438683, 4824.469308376076, 4935.358403407606, 5841.6495010299095, 5862.267216597926, 5912.379200183962, 3742.214135031674, 3781.8865434037025, 3784.243151369737, 3792.355299599075, 3802.2015376770637, 3370.5618269624792, 3869.613038696164, 3870.7677539347164, 3872.7482713623526, 3893.824777885002, 4399.463793709005, 4411.7794110295, 4493.573110554492, 4511.887202819956, 4535.841245556389, 4915.582175515121, 5478.45215478457, 5520.641158324696, 5544.528715059265, 5549.528290095387, 1127.5827943660897, 1248.6878280429892, 1314.8159257703928, 1321.8612045735251, 1327.1486276644027, 735.724103461202, 822.1901427094307, 867.3408209760502, 884.9115587827009, 890.5547226386807, 5143.8456846294675, 5150.480608220584, 5152.407473140562, 5179.161397703836, 5241.161414173714, 4765.11845308619, 5090.363704536933, 5097.8725531862, 5692.438402718793, 5793.304803423616, 4918.704877707349, 5066.594672426197, 5087.643864929493, 5124.410692770304, 5128.333266701295, 2645.790764711449, 2731.4400547887635, 2846.4307138572367, 2913.7960342776228, 2967.4361871244396, 4930.827421040318, 5207.921881171786, 5876.353601103875, 5880.117982302689, 5953.166556682068, 5244.396894357122, 5338.946610533845, 5641.348566173891, 5780.739866706008, 5942.0, 5828.737706803204, 5853.590248682552, 5856.531477481876, 5857.589968702235, 5865.4134586541, 5288.012273065706, 5568.860778480408, 5595.272614560081, 5636.492715655447, 5646.0, 5538.390777014444, 5559.44405559457, 5561.6663000220115, 5593.524550413098, 5601.831945041656, 5812.82561523155, 5819.77784665225, 5844.334364706208, 5871.504610540418, 5931.940680593311, 3192.4413227685527, 3224.8548815302897, 3510.7717998330027, 3605.6839253672747, 3791.090138366717, 5127.512886275797, 5562.499375056264, 5579.0, 5596.804111856066, 5624.072028115315, 4515.68390212682, 4735.360726301905, 4856.737248315393, 5374.355077390738, 5537.695426751407, 5275.973620131875, 5475.589330800286, 5481.123020316731, 5504.724763761876, 5532.391436941853, 4533.478649955166, 4634.026854360487, 5148.9227661584355, 5148.9742551287, 5357.303550538561, 4228.70399072062, 4836.0, 5496.532794712494, 5496.587755918242, 5501.532369748456, 5105.28526006352, 5370.355557332989, 5454.263674403905, 5472.753726082416, 5490.737130459936], "value": 3997.336838310255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.6413864790309, 741.4587351233599, 811.3306522119253, 825.0923983618018, 847.910969348218, 14669.706605867876, 16303.673926521462, 16878.324867005304, 16916.407925722604, 16930.23813928373, 15279.465218717347, 15302.387904483836, 15306.464273750393, 15459.768103478456, 15597.0, 705.3087973785694, 729.6680010595193, 902.0798785239068, 909.0999910089031, 909.2634965677822, 869.2394155114275, 906.5965645287849, 907.9092090790901, 911.4667919267237, 912.0059135542258, 938.9859152112724, 951.4529145741192, 952.5332587032374, 952.5570609666504, 954.0030667952007, 735.2427000189813, 916.1754421021134, 940.9435433873921, 947.6020071569957, 949.8195342884865, 699.9755008574675, 700.425650966208, 700.8002719225047, 700.85982803439, 701.0920857489564, 14885.70228595422, 14957.775633365509, 14974.251287435585, 14984.475543356039, 14989.775153372599, 11140.0, 13624.250666213355, 13633.795493067611, 13672.445164257364, 13749.243791591458, 11320.26418282809, 11545.804388025423, 11565.174129353234, 11787.292762434196, 11968.581099661556, 3324.3185147045037, 3728.3102626014, 3885.2229554089295, 3894.7468414552964, 4047.5750046245016, 82439.5878020606, 83419.16580834174, 84253.15746842575, 84389.73415398774, 84480.99999999939, 11010.284331518425, 11059.0, 11213.55145794159, 11253.887461125447, 11458.541658333574, 21759.347219583437, 21762.673559896615, 21766.45583860416, 21777.891110544348, 21797.0, 7884.519661523146, 7928.5639289838455, 8086.353091752646, 8191.385646076568, 8204.302634276153, 4270.35944608305, 4397.73613583186, 4467.374567560544, 4484.170428470743, 4532.88667783308, 3430.2967891582666, 3519.8768043118366, 3520.15516276097, 3524.7004004659893, 3525.7179425645886, 580.6458060583041, 596.764278110144, 600.3996003996004, 610.2737742086903, 612.6293592376637, 500.8597592674057, 594.7739858853695, 637.1844039629282, 684.3259389501337, 688.3391943733972, 9066.727998159933, 9462.195713364441, 9472.289578281656, 9510.524473776284, 9525.523723813782, 587.3568442555395, 639.8720255948735, 677.678102901118, 681.6182937554985, 701.929807019294, 4074.8777536673947, 4260.339647354665, 4409.625181859514, 4459.375687403766, 4553.931691024637, 76192.61903690446, 78960.0, 79056.0, 79587.20412795914, 92687.53656231676, 68334.65832670804, 69081.65459172672, 70365.0, 70423.59152816916, 71973.0, 67207.3279267201, 68854.65572672104, 71780.0, 74470.25529744741, 77044.0, 9137.908620913706, 9531.38046027006, 9638.373505722106, 9639.421634701941, 9788.951055244963, 45818.312725309814, 46295.0, 46450.535494644624, 47176.52823471721, 47250.76374618105, 60065.69967150311, 60152.0, 61244.0, 63310.41723956938, 63567.6821615889, 13302.401391937192, 13385.732285354434, 13737.381817818423, 13840.93079534596, 14000.719985600435, 14227.573172804832, 14463.27683615836, 14481.710365792414, 14621.707565848836, 14957.40170393172, 13445.865541344461, 13518.25649589282, 13650.385732641857, 13934.58196254114, 14026.158430494203, 13323.600291991255, 13386.799198011842, 13403.0, 13639.045266831226, 13642.795358069437, 13027.413766380347, 13643.931780341034, 13661.590152295446, 13676.794848077487, 13682.110662806886, 10875.347479151276, 10941.288816227237, 11242.381669007957, 11501.137414693763, 11695.941520292685, 10788.352698837782, 10970.502049231463, 11407.543698251977, 11442.713932151762, 11498.367589782642], "value": 7330.031217486848, "unit": "score"}], "name": "dromaeo_css", "value": 7238.892051443277, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 111.0, 113.0, 110.0, 114.0, 111.0, 111.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 117.0, 169.0, 117.0, 120.0, 139.0, 116.0, 144.0, 117.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 189.0, 186.0, 183.0, 186.0, 184.0, 182.0, 183.0, 177.0, 185.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 75.0, 132.0, 81.0, 47.0, 68.0, 90.0, 72.0, 70.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 94.0, 93.0, 71.0, 71.0, 68.0, 98.0, 77.0, 97.0, 135.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 86.0, 88.0, 85.0, 89.0, 85.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 101.0, 92.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 87.0, 84.0, 86.0, 84.0, 84.0, 84.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 56.0, 56.0, 55.0, 55.0, 55.0, 54.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 76.0, 78.0, 72.0, 69.0, 70.0, 69.0, 70.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 86.0, 86.0, 86.0, 89.0, 143.0, 87.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 145.0, 145.0, 138.0, 137.0, 137.0, 142.0, 138.0, 155.0, 141.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 165.0, 166.0, 170.0, 222.0, 222.0, 168.0, 164.0, 173.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 64.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1466.7, "alertThreshold": 2.0}]} 11:17:42 INFO - Return code: 0 11:17:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:17:42 ERROR - # TBPL SUCCESS # 11:17:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:17:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:17:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:17:42 INFO - 2016-05-31 11:17:42.291 screenresolution[1675:9644] starting screenresolution argv=screenresolution get 11:17:42 INFO - 2016-05-31 11:17:42.293 screenresolution[1675:9644] Display 0: 1600x1200x32@60 11:17:42 INFO - 2016-05-31 11:17:42.299 screenresolution[1676:9648] starting screenresolution argv=screenresolution list 11:17:42 INFO - Available Modes on Display 0 11:17:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:17:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:17:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:17:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:17:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:17:42 INFO - Intel Iris: 11:17:42 INFO - Chipset Model: Intel Iris 11:17:42 INFO - Type: GPU 11:17:42 INFO - Bus: Built-In 11:17:42 INFO - VRAM (Dynamic, Max): 1536 MB 11:17:42 INFO - Vendor: Intel (0x8086) 11:17:42 INFO - Device ID: 0x0a2e 11:17:42 INFO - Revision ID: 0x0009 11:17:42 INFO - Displays: 11:17:42 INFO - Display: 11:17:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:17:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:17:42 INFO - Display Serial Number: 1600x1200 60 11:17:42 INFO - Main Display: Yes 11:17:42 INFO - Mirror: Off 11:17:42 INFO - Online: Yes 11:17:42 INFO - Rotation: Supported 11:17:42 INFO - Return code: 0 11:17:42 INFO - Running post-action listener: _resource_record_post_action 11:17:42 INFO - ##### 11:17:42 INFO - ##### Finished run-tests step (success) 11:17:42 INFO - ##### 11:17:42 INFO - Running post-run listener: _resource_record_post_run 11:17:42 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32341504; Write bytes: 696265728; Read time: 385; Write time: 1991 11:17:42 INFO - TinderboxPrint: CPU usage
53.5% 11:17:42 INFO - TinderboxPrint: I/O read bytes / time
32,341,504 / 385 11:17:42 INFO - TinderboxPrint: I/O write bytes / time
696,265,728 / 1,991 11:17:42 INFO - TinderboxPrint: CPU idle
2,255.1 (46.5%) 11:17:42 INFO - TinderboxPrint: CPU system
965.9 (19.9%) 11:17:42 INFO - TinderboxPrint: CPU user
1,632.7 (33.6%) 11:17:42 INFO - TinderboxPrint: Swap in / out
427,450,368 / 0 11:17:42 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 384770560; Write bytes: 377980928; Read time: 29021; Write time: 651 11:17:42 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31407616; Write bytes: 294018560; Read time: 372; Write time: 1265 11:17:42 INFO - Running post-run listener: _upload_blobber_files 11:17:42 INFO - Blob upload gear active. 11:17:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:17:42 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 11:17:42 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'] 11:17:42 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 11:17:43 INFO - (blobuploader) - INFO - Open directory for files ... 11:17:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:17:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:44 INFO - (blobuploader) - INFO - Done attempting. 11:17:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:17:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:17:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:45 INFO - (blobuploader) - INFO - Done attempting. 11:17:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:17:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:17:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:48 INFO - (blobuploader) - INFO - Done attempting. 11:17:48 INFO - (blobuploader) - INFO - Iteration through files over. 11:17:48 INFO - Return code: 0 11:17:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:17:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:17:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfb3e18eaefc2a007717fe87eb817c7516d5b7fd2a372596ee21088ab9b6e8eabfd70e5336befa87f8a7a6983f6f854ef2ccc692ed25d0e4182c95cda2f8d31d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbeaac24e7bb78ddf47485e5c637d426a09d984919cda859c571950c8857c2b4ef30d0d6ad10b7b4203959dbda105ac596d043a83ffd991ca580a7beabe72a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/71e3ce62d71078149f6858eca09b7655266c994f3570884ddcce5a29f5dd5503473ea779b1486d1bc7e4d0faed561094571398389aea860fef2d07e5a32cec34"} 11:17:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:17:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:17:48 INFO - Contents: 11:17:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfb3e18eaefc2a007717fe87eb817c7516d5b7fd2a372596ee21088ab9b6e8eabfd70e5336befa87f8a7a6983f6f854ef2ccc692ed25d0e4182c95cda2f8d31d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbeaac24e7bb78ddf47485e5c637d426a09d984919cda859c571950c8857c2b4ef30d0d6ad10b7b4203959dbda105ac596d043a83ffd991ca580a7beabe72a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/71e3ce62d71078149f6858eca09b7655266c994f3570884ddcce5a29f5dd5503473ea779b1486d1bc7e4d0faed561094571398389aea860fef2d07e5a32cec34"} 11:17:48 INFO - Running post-run listener: copy_logs_to_upload_dir 11:17:48 INFO - Copying logs to upload dir... 11:17:48 INFO - mkdir: /builds/slave/test/build/upload/logs 11:17:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.708655 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-31 11:17:48.225481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 11:17:48.229230) ========= 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.FaOixRfuw5/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.9TVBnd82sb/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/cfb3e18eaefc2a007717fe87eb817c7516d5b7fd2a372596ee21088ab9b6e8eabfd70e5336befa87f8a7a6983f6f854ef2ccc692ed25d0e4182c95cda2f8d31d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbeaac24e7bb78ddf47485e5c637d426a09d984919cda859c571950c8857c2b4ef30d0d6ad10b7b4203959dbda105ac596d043a83ffd991ca580a7beabe72a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/71e3ce62d71078149f6858eca09b7655266c994f3570884ddcce5a29f5dd5503473ea779b1486d1bc7e4d0faed561094571398389aea860fef2d07e5a32cec34"} build_url:https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011037 build_url: 'https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/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/cfb3e18eaefc2a007717fe87eb817c7516d5b7fd2a372596ee21088ab9b6e8eabfd70e5336befa87f8a7a6983f6f854ef2ccc692ed25d0e4182c95cda2f8d31d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbeaac24e7bb78ddf47485e5c637d426a09d984919cda859c571950c8857c2b4ef30d0d6ad10b7b4203959dbda105ac596d043a83ffd991ca580a7beabe72a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/71e3ce62d71078149f6858eca09b7655266c994f3570884ddcce5a29f5dd5503473ea779b1486d1bc7e4d0faed561094571398389aea860fef2d07e5a32cec34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 11:17:48.262874) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:17:48.263606) ========= 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.FaOixRfuw5/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.9TVBnd82sb/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005159 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:17:48.313730) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 11:17:48.314076) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 11:17:48.314421) ========= ========= Total master_lag: 0.20 =========