Closed Bug 1787705 Opened 3 years ago Closed 1 year ago

Intermittent dom/security/test/https-first/browser_mixed_content_console.js | single tracking bug

Categories

(Core :: DOM: Security, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [domsecurity-intermittent])

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=388737643&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/W8UB1BELRAu70SyAd8Xuaw/runs/0/artifacts/public/logs/live_backing.log


[task 2022-08-29T07:23:05.771Z] 07:23:05     INFO - TEST-START | dom/security/test/https-first/browser_mixed_content_console.js
[task 2022-08-29T07:23:05.782Z] 07:23:05     INFO - GECKO(1216) | Chrome file doesn't exist: Z:\task_166175637757781\build\tests\mochitest\browser\dom\security\test\https-first\head.js
[task 2022-08-29T07:23:05.794Z] 07:23:05     INFO - GECKO(1216) | [GPU 2208, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1882
[task 2022-08-29T07:23:05.816Z] 07:23:05     INFO - GECKO(1216) | [Child 5124: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 1ff5dd26c00 == 0 [pid = 5124] [id = 0] [url = https://example.com/browser/dom/security/test/https-first/file_httpsfirst_speculative_connect.html]
[task 2022-08-29T07:23:05.831Z] 07:23:05     INFO - GECKO(1216) | [Child 5124: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (1ff58c6d260) [pid = 5124] [serial = 1] [outer = 0] [url = https://example.com/browser/dom/security/test/https-first/file_httpsfirst_speculative_connect.html]
[task 2022-08-29T07:23:05.844Z] 07:23:05     INFO - GECKO(1216) | [Child 5124: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (1ff5dd2b000) [pid = 5124] [serial = 3] [outer = 0] [url = https://example.com/browser/dom/security/test/https-first/file_httpsfirst_speculative_connect.html]
[task 2022-08-29T07:23:05.845Z] 07:23:05     INFO - GECKO(1216) | [Child 5124: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (1ff5dd27400) [pid = 5124] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:05.845Z] 07:23:05     INFO - GECKO(1216) | [Child 5124, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-08-29T07:23:05.846Z] 07:23:05     INFO - GECKO(1216) | [2022-08-29T07:23:05Z WARN  audioipc2::ipccore] AudioIPC Server RPC: EventLoop drop - closing connection for Token(8) failed: Custom { kind: NotFound, error: "I/O source not registered with `Registry`" }
[task 2022-08-29T07:23:05.846Z] 07:23:05     INFO - GECKO(1216) | [Child 5124, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-08-29T07:23:05.851Z] 07:23:05     INFO - GECKO(1216) | [Child 5124, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4604
[task 2022-08-29T07:23:06.778Z] 07:23:06     INFO - GECKO(1216) | [Parent 6596: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (261e43b4400) [pid = 6596] [serial = 6] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.815Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (1da52ce7000) [pid = 6088] [serial = 13] [outer = 0] [url = moz-extension://a0c1d2df-4917-4e26-a937-5aee8e56bfac/_generated_background_page.html]
[task 2022-08-29T07:23:06.819Z] 07:23:06     INFO - GECKO(1216) | [Parent 6596: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (261e7335000) [pid = 6596] [serial = 11] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.819Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (1da5105a800) [pid = 6088] [serial = 10] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.820Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (1da51055c00) [pid = 6088] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.820Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (1da51058400) [pid = 6088] [serial = 6] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.821Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (1da52ce7c00) [pid = 6088] [serial = 17] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.821Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (1da51057000) [pid = 6088] [serial = 4] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.822Z] 07:23:06     INFO - GECKO(1216) | [Child 6088: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (1da51059800) [pid = 6088] [serial = 8] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:06.822Z] 07:23:06     INFO - GECKO(1216) | [Parent 6596: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (261e0c1c400) [pid = 6596] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:07.147Z] 07:23:07     INFO - GECKO(1216) | [Child 7084, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:198
[task 2022-08-29T07:23:07.207Z] 07:23:07     INFO - GECKO(1216) | [Child 7428: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (25e18d1d000) [pid = 7428] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:07.267Z] 07:23:07     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 238f6620800 == 1 [pid = 7084] [id = 1] [url = about:blank]
[task 2022-08-29T07:23:07.269Z] 07:23:07     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 238f661ec00 == 0 [pid = 7084] [id = 0] [url = about:blank]
[task 2022-08-29T07:23:07.280Z] 07:23:07     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (238f166bb70) [pid = 7084] [serial = 4] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:07.287Z] 07:23:07     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (238f166b260) [pid = 7084] [serial = 1] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.583Z] 07:23:09     INFO - GECKO(1216) | [Child 8304: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (19f7f62a000) [pid = 8304] [serial = 14] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.585Z] 07:23:09     INFO - GECKO(1216) | [Child 8304: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (19f7f61f400) [pid = 8304] [serial = 16] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.784Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (1808491d400) [pid = 5356] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.786Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (18084924400) [pid = 5356] [serial = 8] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.787Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (18084926000) [pid = 5356] [serial = 11] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.788Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (18084923800) [pid = 5356] [serial = 7] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.788Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (18084921000) [pid = 5356] [serial = 5] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.789Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (18084925400) [pid = 5356] [serial = 10] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.789Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (18084920000) [pid = 5356] [serial = 4] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:09.789Z] 07:23:09     INFO - GECKO(1216) | [Child 5356: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (18084926c00) [pid = 5356] [serial = 13] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:10.580Z] 07:23:10     INFO - GECKO(1216) | [Child 8304, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2022-08-29T07:23:10.995Z] 07:23:10     INFO - GECKO(1216) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_166175637757781\AppData\Local\Temp\tmpn9ej37ni.mozrunner\runtests_leaks_tab_pid9208.log
[task 2022-08-29T07:23:11.004Z] 07:23:11     INFO - GECKO(1216) | [9208, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-29T07:23:11.036Z] 07:23:11     INFO - TEST-INFO | started process screenshot
[task 2022-08-29T07:23:11.132Z] 07:23:11     INFO - TEST-INFO | screenshot: exit 0
[task 2022-08-29T07:23:11.140Z] 07:23:11     INFO - Buffered messages logged at 07:23:05
[task 2022-08-29T07:23:11.141Z] 07:23:11     INFO - Entering test bound 
[task 2022-08-29T07:23:11.141Z] 07:23:11     INFO - Console message: [JavaScript Warning: "HTTPS-First Mode: Upgrading insecure speculative TCP connection http://example.com/browser/dom/security/test/https-first/file_mixed_content_console.html to use https."]
[task 2022-08-29T07:23:11.142Z] 07:23:11     INFO - TEST-PASS | dom/security/test/https-first/browser_mixed_content_console.js | request got upgraded - 
[task 2022-08-29T07:23:11.142Z] 07:23:11     INFO - TEST-PASS | dom/security/test/https-first/browser_mixed_content_console.js | correct top-level request - 
[task 2022-08-29T07:23:11.143Z] 07:23:11     INFO - Console message: [JavaScript Warning: "HTTPS-First Mode: Upgrading insecure request http://example.com/browser/dom/security/test/https-first/file_mixed_content_console.html to use https."]
[task 2022-08-29T07:23:11.143Z] 07:23:11     INFO - Buffered messages logged at 07:23:11
[task 2022-08-29T07:23:11.143Z] 07:23:11     INFO - TEST-PASS | dom/security/test/https-first/browser_mixed_content_console.js | request got upgraded - 
[task 2022-08-29T07:23:11.144Z] 07:23:11     INFO - Buffered messages finished
[task 2022-08-29T07:23:11.144Z] 07:23:11     INFO - TEST-UNEXPECTED-FAIL | dom/security/test/https-first/browser_mixed_content_console.js | correct top-level request - 
[task 2022-08-29T07:23:11.144Z] 07:23:11     INFO - Stack trace:
[task 2022-08-29T07:23:11.145Z] 07:23:11     INFO - chrome://mochikit/content/browser-test.js:test_ok:1440
[task 2022-08-29T07:23:11.145Z] 07:23:11     INFO - chrome://mochitests/content/browser/dom/security/test/https-first/browser_mixed_content_console.js:on_console_message:39
[task 2022-08-29T07:23:11.146Z] 07:23:11     INFO - Console message: [JavaScript Warning: "HTTPS-First Mode: Upgrading insecure request https://example.com/browser/dom/security/test/https-first/file_mixed_content_console.html failed. Downgrading to http again."]
[task 2022-08-29T07:23:11.146Z] 07:23:11     INFO - GECKO(1216) | [Child 8304, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-08-29T07:23:11.147Z] 07:23:11     INFO - GECKO(1216) | [Child 8992: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 164d4f20c00 == 1 [pid = 8992] [id = 0]
[task 2022-08-29T07:23:11.147Z] 07:23:11     INFO - GECKO(1216) | [Child 8992: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (164cff6b260) [pid = 8992] [serial = 1] [outer = 0]
[task 2022-08-29T07:23:11.148Z] 07:23:11     INFO - GECKO(1216) | [Child 8992: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (164d4f21400) [pid = 8992] [serial = 2] [outer = 164cff6b260]
[task 2022-08-29T07:23:11.191Z] 07:23:11     INFO - GECKO(1216) | [Child 8304, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2022-08-29T07:23:11.301Z] 07:23:11     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-08-29T07:23:11.302Z] 07:23:11     INFO - TEST-UNEXPECTED-FAIL | dom/security/test/https-first/browser_mixed_content_console.js | Uncaught exception in test - undefined - timed out after 50 tries.
[task 2022-08-29T07:23:11.302Z] 07:23:11     INFO - Leaving test bound 
[task 2022-08-29T07:23:11.306Z] 07:23:11     INFO - GECKO(1216) | [Child 8992: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (164d4f24800) [pid = 8992] [serial = 3] [outer = 164cff6b260]
[task 2022-08-29T07:23:11.354Z] 07:23:11     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source http://example.com/browser/dom/security/test/https-first/barfoo." {file: "http://example.com/browser/dom/security/test/https-first/file_mixed_content_console.html" line: 10}]
[task 2022-08-29T07:23:11.418Z] 07:23:11     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (238f661f400) [pid = 7084] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:11.420Z] 07:23:11     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (238f6625000) [pid = 7084] [serial = 6] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:11.420Z] 07:23:11     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (238f6621c00) [pid = 7084] [serial = 3] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:11.421Z] 07:23:11     INFO - GECKO(1216) | [Child 7084: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (238f6624000) [pid = 7084] [serial = 5] [outer = 0] [url = about:blank]
[task 2022-08-29T07:23:11.758Z] 07:23:11     INFO - GECKO(1216) | MEMORY STAT | vsize 2104260MB | vsizeMaxContiguous 67751856MB | residentFast 286MB | heapAllocated 95MB
[task 2022-08-29T07:23:11.760Z] 07:23:11     INFO - TEST-OK | dom/security/test/https-first/browser_mixed_content_console.js | took 5979ms
[task 2022-08-29T07:23:11.767Z] 07:23:11     INFO - GECKO(1216) | [Child 8304: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 19f7f620400 == 2 [pid = 8304] [id = 7]
[task 2022-08-29T07:23:11.768Z] 07:23:11     INFO - GECKO(1216) | [Child 8304: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (19f7a66b7d0) [pid = 8304] [serial = 19] [outer = 0]
[task 2022-08-29T07:23:11.768Z] 07:23:11     INFO - GECKO(1216) | [Child 8304: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (19f7f624000) [pid = 8304] [serial = 20] [outer = 19f7a66b7d0]
[task 2022-08-29T07:23:11.774Z] 07:23:11     INFO - GECKO(1216) | [Child 8992, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-08-29T07:23:11.788Z] 07:23:11     INFO - checking window state
[task 2022-08-29T07:23:11.799Z] 07:23:11     INFO - TEST-START | dom/security/test/https-first/browser_navigation.js
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.