Closed Bug 1498333 Opened 7 years ago Closed 7 years ago

Intermittent [tier2] test-verify browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | expecting an HTTP/1.1 GET request -

Categories

(Firefox :: Site Identity, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: shindli [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=204835596&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/WfMd5Zy8TDKqMMmwuVxRig/runs/0/artifacts/public/logs/live_backing.log 18:25:51 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | expecting an HTTP/1.1 GET request - 18:25:51 INFO - Stack trace: 18:25:51 INFO - chrome://mochitests/content/browser/browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js:onInputStreamReady:54 18:25:51 INFO - TEST-PASS | browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | expecting an HTTP/1.1 GET request - 18:25:51 INFO - Console message: [JavaScript Error: "The character encoding of the plain text document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the file needs to be declared in the transfer protocol or file needs to use a byte order mark as an encoding signature." {file: "http://example.com/" line: 0}] 18:25:51 INFO - TEST-PASS | browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | identity should be 'unknown' - 18:25:51 INFO - Leaving test bound 18:25:51 INFO - GECKO(1604) | MEMORY STAT | vsize 685MB | vsizeMaxContiguous 769MB | residentFast 217MB | heapAllocated 95MB 18:25:51 INFO - TEST-OK | browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | took 905ms 18:25:51 INFO - checking window state 18:25:51 INFO - Console message: PAC file installed from data: URI 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 4632 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 2072 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 1132 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 3732 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 4140 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 4440 18:25:53 INFO - GECKO(1604) | Completed ShutdownLeaks collections in process 2992 18:25:53 INFO - TEST-START | Shutdown 18:25:53 INFO - Browser Chrome Test Summary 18:25:53 INFO - Passed: 3 18:25:53 INFO - Failed: 1 18:25:53 INFO - Todo: 0 18:25:53 INFO - Mode: e10s 18:25:53 INFO - *** End BrowserChrome Test Results *** 18:25:53 INFO - GECKO(1604) | [Parent 2992, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 3732, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 3732, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Parent 2992, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4760, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4760, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/s[Parent 2992, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4632, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4632, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4440, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4440, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Parent 2992, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 4140, Chrome_ChildThrea[Parent 2992, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 2072, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 2072, Chrome_ChildThread] WARNING: pipe er[Parent 2992, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 1132, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 18:25:53 INFO - GECKO(1604) | [Child 1132, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ip[Parent 2992, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188 18:25:53 INFO - GECKO(1604) | 1539282353850 Marionette DEBUG Received observer notification xpcom-will-shutdown 18:25:53 INFO - GECKO(1604) | 1539282353851 Marionette INFO Stopped listening on port 2828 18:25:53 INFO - GECKO(1604) | 1539282353851 Marionette DEBUG Remote service is inactive 18:25:54 INFO - TEST-INFO | Main app process: exit 0 18:25:54 INFO - runtests.py | Application ran for: 0:00:05.391000 18:25:54 INFO - zombiecheck | Reading PID log: c:\users\task_1539281457\appdata\local\temp\tmpu6tb7tpidlog 18:25:54 INFO - ==> process 2992 launched child process 1132 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.0.76955888\1791449940" -childID 1 -isForBrowser -prefsHandle 1992 -prefMapHandle 1384 -prefsLen 1 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 2068 tab) 18:25:54 INFO - ==> process 2992 launched child process 3732 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.6.869860732\196457919" -childID 2 -isForBrowser -prefsHandle 1428 -prefMapHandle 1968 -prefsLen 1 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 1956 tab) 18:25:54 INFO - ==> process 2992 launched child process 2072 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.12.1622341842\2078956336" -childID 3 -isForBrowser -prefsHandle 2304 -prefMapHandle 2300 -prefsLen 41 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 1956 tab) 18:25:54 INFO - ==> process 2992 launched child process 4140 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.18.1962296710\1382648066" -childID 4 -isForBrowser -prefsHandle 3112 -prefMapHandle 3116 -prefsLen 6423 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 3128 tab) 18:25:54 INFO - ==> process 2992 launched child process 4440 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.24.154026758\1109504195" -childID 5 -isForBrowser -prefsHandle 3464 -prefMapHandle 3472 -prefsLen 6485 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 3484 tab) 18:25:54 INFO - ==> process 2992 launched child process 4632 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.30.373455039\1540361529" -childID 6 -isForBrowser -prefsHandle 3884 -prefMapHandle 3876 -prefsLen 6642 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 3900 tab) 18:25:54 INFO - ==> process 2992 launched child process 4760 ("Z:\task_1539281457\build\application\firefox\firefox.exe" -contentproc --channel="2992.36.1553618509\917477065" -childID 7 -isForBrowser -prefsHandle 4016 -prefMapHandle 4020 -prefsLen 6642 -prefMapSize 187215 -schedulerPrefs 0001,2 -parentBuildID 20181011171906 -greomni "Z:\task_1539281457\build\application\firefox\omni.ja" -appomni "Z:\task_1539281457\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1539281457\build\application\firefox\browser" - 2992 "\\.\pipe\gecko-crash-server-pipe.2992" 4032 tab) 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 4632 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 4140 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 4760 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 1132 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 4440 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 3732 18:25:54 INFO - zombiecheck | Checking for orphan process with PID: 2072 18:25:54 INFO - Stopping web server 18:25:54 INFO - Stopping web socket server 18:25:54 INFO - Stopping ssltunnel 18:25:54 WARNING - leakcheck | refcount logging is off, so leaks can't be detected! 18:25:54 INFO - runtests.py | Running tests: end. 18:25:54 INFO - Buffered messages finished 18:25:54 INFO - TEST-INFO | checking window state 18:25:54 INFO - Browser Chrome Test Summary 18:25:54 INFO - Passed: 3 18:25:54 INFO - Failed: 1 18:25:54 INFO - Todo: 0 18:25:54 INFO - Mode: e10s 18:25:54 INFO - *** End BrowserChrome Test Results *** 18:25:54 INFO - Buffered messages finished 18:25:54 INFO - SUITE-END | took 6s 18:25:54 INFO - SUITE-START | Running 1 tests 18:25:54 INFO - ::: 18:25:54 INFO - ::: Test verification summary for: 18:25:54 INFO - ::: 18:25:54 INFO - ::: browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js 18:25:54 INFO - ::: 18:25:54 INFO - ::: 1. Run each test 10 times in one browser. : FAIL 18:25:54 INFO - ::: 2. Run each test 5 times in a new browser each time. : not run / incomplete 18:25:54 INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : not run / incomplete 18:25:54 INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete 18:25:54 INFO - ::: 18:25:54 INFO - ::: Test verification FAILED! 18:25:54 INFO - ::: 18:25:54 INFO - Buffered messages finished 18:25:54 INFO - SUITE-END | took 0s 18:25:54 INFO - Return code: 0 18:25:54 INFO - TinderboxPrint: mochitest-browser-chrome<br/>4/<em class="testfail">1</em>/0 18:25:54 WARNING - # TBPL WARNING # 18:25:54 WARNING - setting return code to 1 18:25:54 WARNING - TinderboxPrint: Per-test run of ...\browser_secure_transport_insecure_scheme.js<br/>: WARNING 18:25:54 INFO - Per-test suites: {} 18:25:54 INFO - Per-test suites: {} 18:25:54 INFO - Running post-action listener: _package_coverage_data 18:25:54 INFO - Running post-action listener: _resource_record_post_action 18:25:54 INFO - Running post-action listener: process_java_coverage_data 18:25:54 INFO - [mozharness: 2018-10-11 18:25:54.267000Z] Finished run-tests step (success) 18:25:54 INFO - Running post-run listener: _resource_record_post_run 18:25:54 INFO - Validating Perfherder data against Z:\task_1539281457\mozharness\external_tools\performance-artifact-schema.json 18:25:54 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 9.809090909090905}, {"name": "io_write_bytes", "value": 214614016}, {"name": "io.read_bytes", "value": 14078976}, {"name": "io_write_time", "value": 2}, {"name": "io_read_time", "value": 0}], "extraOptions": ["e10s", "taskcluster-c4.2xlarge"], "name": ".overall"}, {"subtests": [{"name": "time", "value": 3.1110000610351562}, {"name": "cpu_percent", "value": 9.575}], "name": ".install"}, {"subtests": [{"name": "time", "value": 0.0009999275207519531}], "name": ".stage-files"}, {"subtests": [{"name": "time", "value": 19.378999948501587}, {"name": "cpu_percent", "value": 9.898026315789473}], "name": ".run-tests"}]} 18:25:54 INFO - Total resource usage - Wall time: 22s; CPU: 10.0%; Read bytes: 14078976; Write bytes: 214614016; Read time: 0; Write time: 2 18:25:54 INFO - TinderboxPrint: CPU usage<br/>9.9% 18:25:54 INFO - TinderboxPrint: I/O read bytes / time<br/>14,078,976 / 0 18:25:54 INFO - TinderboxPrint: I/O write bytes / time<br/>214,614,016 / 2 18:25:54 INFO - TinderboxPrint: CPU idle<br/>159.5 (90.2%) 18:25:54 INFO - TinderboxPrint: CPU system<br/>6.1 (3.4%) 18:25:54 INFO - TinderboxPrint: CPU user<br/>11.0 (6.2%) 18:25:54 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 114688; Write bytes: 83479040; Read time: 0; Write time: 1 18:25:54 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:25:54 INFO - run-tests - Wall time: 19s; CPU: 10.0%; Read bytes: 13958144; Write bytes: 48176640; Read time: 0; Write time: 1 18:25:54 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:54 INFO - Copying logs to upload dir... 18:25:54 INFO - mkdir: Z:\task_1539281457\build\upload\logs 18:25:54 INFO - Copying logs to upload dir... 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\localconfig.json" 18:25:54 INFO - Return code: 0 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\log_info.log" 18:25:54 INFO - Return code: 0 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\log_raw.log" 18:25:54 INFO - Return code: 0 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\log_warning.log" 18:25:54 INFO - Return code: 0 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\log_critical.log" 18:25:54 INFO - Return code: 0 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\log_error.log" 18:25:54 INFO - Return code: 0 18:25:54 INFO - Using _rmtree_windows ... 18:25:54 INFO - Running command: del /F /Q "Z:\task_1539281457\build\upload\logs\log_fatal.log" 18:25:54 INFO - Return code: 0 18:25:54 WARNING - returning nonzero exit status 1 [taskcluster 2018-10-11T18:25:54.505Z] Exit Code: 1 [taskcluster 2018-10-11T18:25:54.505Z] User Time: 0s [taskcluster 2018-10-11T18:25:54.505Z] Kernel Time: 0s [taskcluster 2018-10-11T18:25:54.505Z] Wall Time: 10m42.1993488s [taskcluster 2018-10-11T18:25:54.505Z] Result: FAILED [taskcluster 2018-10-11T18:25:54.505Z] === Task Finished === [taskcluster 2018-10-11T18:25:54.505Z] Task Duration: 10m42.2023512s [taskcluster 2018-10-11T18:25:55.018Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:55.740Z] Uploading artifact public/logs/log_critical.log from file logs\log_critical.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:56.118Z] Uploading artifact public/logs/log_error.log from file logs\log_error.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:56.486Z] Uploading artifact public/logs/log_fatal.log from file logs\log_fatal.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:56.855Z] Uploading artifact public/logs/log_info.log from file logs\log_info.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:57.522Z] Uploading artifact public/logs/log_raw.log from file logs\log_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:57.936Z] Uploading artifact public/logs/log_warning.log from file logs\log_warning.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:58.335Z] Uploading artifact public/test_info/browser-chrome_errorsummary.log from file build\blobber_upload_dir\browser-chrome_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:58.752Z] Uploading artifact public/test_info/browser-chrome_raw.log from file build\blobber_upload_dir\browser-chrome_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:59.124Z] Uploading artifact public/test_info/manifests.list from file build\blobber_upload_dir\manifests.list with content encoding "gzip", mime type "application/octet-stream" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:25:59.536Z] Uploading artifact public/test_info/mozilla-test-fail-screenshot_1cg5lu.png from file build\blobber_upload_dir\mozilla-test-fail-screenshot_1cg5lu.png with content encoding "", mime type "image/png" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:26:00.043Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:26:00.425Z] Uploading artifact public/test_info/system-info.log from file build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2019-10-11T17:21:15.763Z [taskcluster 2018-10-11T18:26:01.043Z] Uploading redirect artifact public/logs/live.log to URL https://queue.taskcluster.net/v1/task/WfMd5Zy8TDKqMMmwuVxRig/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2019-10-11T17:21:15.763Z [taskcluster:error] exit status 1
Summary: Intermittent browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | expecting an HTTP/1.1 GET request - → Intermittent [tier2] test-verify browser/base/content/test/siteIdentity/browser_secure_transport_insecure_scheme.js | expecting an HTTP/1.1 GET request -
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
This is still a valid bug, it hasn't gone away.
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.