1900297 - Intermittent toolkit/components/printing/tests/browser_print_frame.js | single tracking bug

Status: RESOLVED INCOMPLETE

(Toolkit :: Printing, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2024-06-03T05:07:10.125Z] 05:07:10     INFO - TEST-PASS | toolkit/components/printing/tests/browser_print_frame.js | Element must be visible to click - 
[task 2024-06-03T05:07:10.126Z] 05:07:10     INFO - Buffered messages finished
[task 2024-06-03T05:07:10.126Z] 05:07:10     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_print_frame.js | Uncaught exception in test bound print_frame - Wait for dialog to close - timed out after 50 tries.
[task 2024-06-03T05:07:10.127Z] 05:07:10     INFO - Leaving test bound print_frame
[task 2024-06-03T05:07:10.127Z] 05:07:10     INFO - GECKO(9312) | [Child 6512: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 221c86f7000 == 0 [pid = 6512] [id = 10] [url = about:blank]
[task 2024-06-03T05:07:10.128Z] 05:07:10     INFO - GECKO(9312) | [Child 6800, Main Thread] ###!!! ASSERTION: User did not call nsIDocumentViewer::Destroy: '!mPresShell && !mPresContext', file /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp:561
[task 2024-06-03T05:07:10.129Z] 05:07:10     INFO - GECKO(9312) | #01: NS_DebugBreak(unsigned int, char const*, char const*, char const*, int) [xpcom/base/nsDebugImpl.cpp:491]
[task 2024-06-03T05:07:10.129Z] 05:07:10     INFO - GECKO(9312) | #02: nsDocumentViewer::~nsDocumentViewer() [layout/base/nsDocumentViewer.cpp:562]
[task 2024-06-03T05:07:10.130Z] 05:07:10     INFO - GECKO(9312) | #03: nsDocumentViewer::Release() [layout/base/nsDocumentViewer.cpp:534]
[task 2024-06-03T05:07:10.131Z] 05:07:10     INFO - GECKO(9312) | #04: nsPrintCompletionEvent::~nsPrintCompletionEvent() [layout/printing/nsPrintJob.cpp:2076]
[task 2024-06-03T05:07:10.132Z] 05:07:10     INFO - GECKO(9312) | #05: mozilla::Runnable::Release() [xpcom/threads/nsThreadUtils.cpp:66]
[task 2024-06-03T05:07:10.132Z] 05:07:10     INFO - GECKO(9312) | #06: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:582]
[task 2024-06-03T05:07:10.133Z] 05:07:10     INFO - GECKO(9312) | #07: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:907]
[task 2024-06-03T05:07:10.134Z] 05:07:10     INFO - GECKO(9312) | #08: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:730]
[task 2024-06-03T05:07:10.134Z] 05:07:10     INFO - GECKO(9312) | #09: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:516]
[task 2024-06-03T05:07:10.135Z] 05:07:10     INFO - GECKO(9312) | #10: mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:234:7'>::Run() [xpcom/threads/nsThreadUtils.h:549]
[task 2024-06-03T05:07:10.136Z] 05:07:10     INFO - GECKO(9312) | #11: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1203]
[task 2024-06-03T05:07:10.136Z] 05:07:10     INFO - GECKO(9312) | #12: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:480]
[task 2024-06-03T05:07:10.136Z] 05:07:10     INFO - GECKO(9312) | #13: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:85]
[task 2024-06-03T05:07:10.137Z] 05:07:10     INFO - GECKO(9312) | #14: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:364]
[task 2024-06-03T05:07:10.138Z] 05:07:10     INFO - GECKO(9312) | #15: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:346]
[task 2024-06-03T05:07:10.138Z] 05:07:10     INFO - GECKO(9312) | #16: nsBaseAppShell::Run() [widget/nsBaseAppShell.cpp:150]
[task 2024-06-03T05:07:10.139Z] 05:07:10     INFO - GECKO(9312) | #17: nsAppShell::Run() [widget/windows/nsAppShell.cpp:822]
[task 2024-06-03T05:07:10.140Z] 05:07:10     INFO - GECKO(9312) | #18: XRE_RunAppShell() [toolkit/xre/nsEmbedFunctions.cpp:712]
[task 2024-06-03T05:07:10.140Z] 05:07:10     INFO - GECKO(9312) | #19: mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:235]
[task 2024-06-03T05:07:10.140Z] 05:07:10     INFO - GECKO(9312) | #20: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:364]
[task 2024-06-03T05:07:10.141Z] 05:07:10     INFO - GECKO(9312) | #21: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:346]
[task 2024-06-03T05:07:10.142Z] 05:07:10     INFO - GECKO(9312) | #22: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:651]
[task 2024-06-03T05:07:10.242Z] 05:07:10     INFO - GECKO(9312) | #23: NS_internal_main(int, char**, char**) [browser/app/nsBrowserApp.cpp:378]
[task 2024-06-03T05:07:10.243Z] 05:07:10     INFO - GECKO(9312) | #24: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:151]
[task 2024-06-03T05:07:10.244Z] 05:07:10     INFO - GECKO(9312) | #25: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/browser/app/D:/a/_work/1/s/src/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2024-06-03T05:07:10.276Z] 05:07:10     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2024-06-03T05:07:10.276Z] 05:07:10     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2024-06-03T05:07:10.277Z] 05:07:10     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2024-06-03T05:07:10.277Z] 05:07:10     INFO - GECKO(9312) | #26: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x1257d]
[task 2024-06-03T05:07:10.299Z] 05:07:10     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2024-06-03T05:07:10.300Z] 05:07:10     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2024-06-03T05:07:10.301Z] 05:07:10     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2024-06-03T05:07:10.302Z] 05:07:10     INFO - GECKO(9312) | #27: RtlUserThreadStart [C:\Windows\SYSTEM32\ntdll.dll + 0x5aa48]
[task 2024-06-03T05:07:10.302Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 20e5c7f1400 == 2 [pid = 6800] [id = 7] [url = about:blank]
[task 2024-06-03T05:07:10.303Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 20e5c7ec000 == 1 [pid = 6800] [id = 6] [url = about:srcdoc]
[task 2024-06-03T05:07:10.304Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 20e557da800 == 0 [pid = 6800] [id = 5] [url = data:text/html,Something else <iframe id="f" srcdoc="<a href='about:mozilla'>some text</a>"></iframe>]
[task 2024-06-03T05:07:10.304Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (20e557d9400) [pid = 6800] [serial = 2] [outer = 0] [url = about:blank]
[task 2024-06-03T05:07:10.305Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (20e557dc400) [pid = 6800] [serial = 3] [outer = 0] [url = data:text/html,%0A%20%20%3Ca%20rel%3D%22noopener%22%20target%3D%22_blank%22%20href%3D%22https%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fcomponents%2Fprinting%2Ftests%2FsimplifyArticleSample.html%22%3EOpen%20the%20window%3C%2Fa%3E%0A]
[task 2024-06-03T05:07:10.306Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (20e5c7eb000) [pid = 6800] [serial = 5] [outer = 0] [url = about:blank]
[task 2024-06-03T05:07:10.307Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (20e5c7f4800) [pid = 6800] [serial = 14] [outer = 0] [url = about:srcdoc]
[task 2024-06-03T05:07:10.308Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (20e5c7f4400) [pid = 6800] [serial = 13] [outer = 0] [url = about:blank]
[task 2024-06-03T05:07:10.308Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (20e5c7f0800) [pid = 6800] [serial = 11] [outer = 0] [url = about:blank]
[task 2024-06-03T05:07:10.309Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (20e5c7ef800) [pid = 6800] [serial = 9] [outer = 0] [url = data:text/html,<html><h1>Top level text</h1><iframe srcdoc='<a href="about:mozilla">Inner frame</a>' id="f"></iframe></html>]
[task 2024-06-03T05:07:10.310Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (20e5c7ed400) [pid = 6800] [serial = 8] [outer = 0] [url = about:blank]
[task 2024-06-03T05:07:10.310Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (20e5c7eb800) [pid = 6800] [serial = 6] [outer = 0] [url = about:blank]
[task 2024-06-03T05:07:10.311Z] 05:07:10     INFO - GECKO(9312) | MEMORY STAT | vsize 2112610MB | vsizeMaxContiguous 66057658MB | residentFast 367MB | heapAllocated 118MB
[task 2024-06-03T05:07:10.311Z] 05:07:10     INFO - TEST-OK | toolkit/components/printing/tests/browser_print_frame.js | took 9396ms
[task 2024-06-03T05:07:10.312Z] 05:07:10     INFO - GECKO(9312) | [Child 6512: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 221c86f2800 == 1 [pid = 6512] [id = 11]
[task 2024-06-03T05:07:10.312Z] 05:07:10     INFO - GECKO(9312) | [Child 6512: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (221cbaf9020) [pid = 6512] [serial = 25] [outer = 0]
[task 2024-06-03T05:07:10.313Z] 05:07:10     INFO - GECKO(9312) | [Child 6512: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (221c86f3000) [pid = 6512] [serial = 26] [outer = 221cbaf9020]
[task 2024-06-03T05:07:10.314Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (20e5adfb020) [pid = 6800] [serial = 15] [outer = 0] [url = data:text/html,Something else <iframe id="f" srcdoc="<a href='about:mozilla'>some text</a>"></iframe>]
[task 2024-06-03T05:07:10.315Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (20e5c7a7100) [pid = 6800] [serial = 20] [outer = 0] [url = about:srcdoc]
[task 2024-06-03T05:07:10.316Z] 05:07:10     INFO - GECKO(9312) | [Child 6800: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (20e5c7a6f20) [pid = 6800] [serial = 18] [outer = 0] [url = about:srcdoc]
[task 2024-06-03T05:07:10.316Z] 05:07:10     INFO - checking window state
[task 2024-06-03T05:07:10.319Z] 05:07:10     INFO - TEST-START | toolkit/components/printing/tests/browser_print_in_container.js

Comment 1

[Intermittent Failures Robot]

1 failures in 3649 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1900297&startday=2024-06-03&endday=2024-06-09&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 3

[Intermittent Failures Robot]

1 failures were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Table

	no_variant
windows11-64-24h2--/asan	1

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1900297&startday=2025-03-31&endday=2025-04-06&tree=all