Closed Bug 1769714 Opened 2 years ago Closed 2 years ago

Intermittent Assertion failed: IsGUIThread(false), file /builds/worker/checkouts/gecko/third_party/libwebrtc/modules/desktop_capture/mouse_cursor_monitor_win.cc, line 94

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED FIXED
112 Branch
Tracking Status
firefox-esr91 --- unaffected
firefox-esr102 --- wontfix
firefox100 --- unaffected
firefox101 --- unaffected
firefox102 --- wontfix
firefox103 --- wontfix
firefox110 --- wontfix
firefox111 --- wontfix
firefox112 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: pehrsons)

References

(Regression)

Details

(Keywords: assertion, intermittent-failure, regression)

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=378311819&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eBTj9WsCSAa9tT1IaFDuXw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eBTj9WsCSAa9tT1IaFDuXw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2022-05-17T06:48:27.299Z] 06:48:27     INFO - TEST-START | /screen-capture/getdisplaymedia.https.html
[task 2022-05-17T06:48:27.313Z] 06:48:27     INFO - Setting pref media.navigator.permission.disabled to true
[task 2022-05-17T06:48:27.329Z] 06:48:27     INFO - Setting pref media.navigator.streams.fake to true
[task 2022-05-17T06:48:27.352Z] 06:48:27     INFO - Setting pref dom.security.featurePolicy.header.enabled to true
[task 2022-05-17T06:48:27.376Z] 06:48:27     INFO - Setting pref dom.security.featurePolicy.webidl.enabled to true
[task 2022-05-17T06:48:28.207Z] 06:48:28     INFO - PID 5428 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824_tab_pid5372.log
[task 2022-05-17T06:48:28.217Z] 06:48:28     INFO - PID 5428 | [5372, 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-05-17T06:48:28.229Z] 06:48:28     INFO - PID 5428 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824_tab_pid7584.log
[task 2022-05-17T06:48:28.229Z] 06:48:28     INFO - PID 5428 | [7584, 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-05-17T06:48:28.385Z] 06:48:28     INFO - PID 5428 | [Parent 7476, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp:2619
[task 2022-05-17T06:48:28.455Z] 06:48:28     INFO - PID 5428 | [Parent 7476, Main Thread] WARNING: Forced to copy ObserverTable due to nested notifications: file /builds/worker/checkouts/gecko/image/ProgressTracker.h:85
[task 2022-05-17T06:48:32.537Z] 06:48:32     INFO - PID 5428 | [Parent 7476, IPDL Background] WARNING: IPC Connection Error: [Parent][PBackgroundParent] RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1908
[task 2022-05-17T06:48:32.555Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.560Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.561Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.564Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.572Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.575Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.578Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-05-17T06:48:32.579Z] 06:48:32     INFO - PID 5428 | [2022-05-17T06:48:32Z WARN  audioipc2::ipccore] AudioIPC Server RPC: EventLoop drop - closing connection for Token(2) failed: Custom { kind: NotFound, error: "I/O source not registered with `Registry`" }
[task 2022-05-17T06:48:32.580Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-05-17T06:48:32.581Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.582Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.582Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.583Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-17T06:48:32.586Z] 06:48:32     INFO - PID 5428 | [Child 6040, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4571
[task 2022-05-17T06:48:35.446Z] 06:48:35     INFO - PID 5428 | [Child 1844, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:197
[task 2022-05-17T06:48:58.372Z] 06:48:58     INFO - PID 5428 | [GPU 7112, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:365
[task 2022-05-17T06:48:58.710Z] 06:48:58     INFO - PID 5428 | [Parent 7476, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp:2619
<...>
[task 2022-05-17T06:49:08.507Z] 06:49:08     INFO - PID 5428 | [GPU 7112, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:365
[task 2022-05-17T06:49:08.779Z] 06:49:08     INFO - PID 5428 | Assertion failed: IsGUIThread(false), file /builds/worker/checkouts/gecko/third_party/libwebrtc/modules/desktop_capture/mouse_cursor_monitor_win.cc, line 94
[task 2022-05-17T06:49:11.034Z] 06:49:11     INFO - PID 5428 | [GPU 7112, IPC I/O Child] WARNING: [F2E9C53163BB23D1.23C9FC2F7F37BA9F]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:352
[task 2022-05-17T06:49:11.034Z] 06:49:11     INFO - PID 5428 | [GPU 7112, IPC I/O Child] WARNING: [F2E9C53163BB23D1.23C9FC2F7F37BA9F]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:352
[task 2022-05-17T06:49:11.034Z] 06:49:11     INFO - PID 5428 | [GPU 7112, IPC I/O Child] WARNING: [F2E9C53163BB23D1.23C9FC2F7F37BA9F]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:352
[task 2022-05-17T06:49:11.034Z] 06:49:11     INFO - PID 5428 | [GPU 7112, IPC I/O Child] WARNING: [F2E9C53163BB23D1.23C9FC2F7F37BA9F]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:352
[task 2022-05-17T06:49:11.043Z] 06:49:11     INFO - PID 5428 | [GPU 7112, IPC I/O Child] WARNING: [F2E9C53163BB23D1.23C9FC2F7F37BA9F]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:352
[task 2022-05-17T06:49:11.044Z] 06:49:11     INFO - PID 5428 | [GPU 7112, Main Thread] WARNING: Shutting down GPU process early due to a crash!: file /builds/worker/checkouts/gecko/gfx/ipc/GPUParent.cpp:656
[task 2022-05-17T06:51:42.302Z] 06:51:42     INFO - Got timeout in harness
[task 2022-05-17T06:51:42.305Z] 06:51:42     INFO - TEST-UNEXPECTED-TIMEOUT | /screen-capture/getdisplaymedia.https.html | TestRunner hit external timeout (this may indicate a hang)
[task 2022-05-17T06:51:42.305Z] 06:51:42     INFO - TEST-INFO took 195005ms
[task 2022-05-17T06:51:42.306Z] 06:51:42     INFO - No more tests
[task 2022-05-17T06:51:42.307Z] 06:51:42     INFO - Browser exited with return code 3221226505
[task 2022-05-17T06:51:42.308Z] 06:51:42     INFO - PROCESS LEAKS C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824.log
[task 2022-05-17T06:51:42.309Z] 06:51:42     INFO - leakcheck | Processing log file C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824.log for scope /
[task 2022-05-17T06:51:42.309Z] 06:51:42     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 460800 bytes
[task 2022-05-17T06:51:42.310Z] 06:51:42     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2022-05-17T06:51:42.310Z] 06:51:42     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2022-05-17T06:51:42.311Z] 06:51:42     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2022-05-17T06:51:42.311Z] 06:51:42     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2022-05-17T06:51:42.312Z] 06:51:42     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2022-05-17T06:51:42.312Z] 06:51:42     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2022-05-17T06:51:42.313Z] 06:51:42     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2022-05-17T06:51:42.313Z] 06:51:42     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2022-05-17T06:51:42.314Z] 06:51:42     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2022-05-17T06:51:42.314Z] 06:51:42     INFO - leakcheck | Processing leak log file C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824.log
[task 2022-05-17T06:51:42.315Z] 06:51:42     INFO - TEST-UNEXPECTED-FAIL | leakcheck | default missing output line for total leaks!
[task 2022-05-17T06:51:42.315Z] 06:51:42     INFO - leakcheck | Processing leak log file C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824_gpu_pid7112.log
[task 2022-05-17T06:51:42.316Z] 06:51:42     INFO - TEST-UNEXPECTED-FAIL | leakcheck | gpu missing output line for total leaks!
[task 2022-05-17T06:51:42.316Z] 06:51:42     INFO - leakcheck | Processing leak log file C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824_tab_pid1844.log
[task 2022-05-17T06:51:42.316Z] 06:51:42     INFO - TEST-INFO | leakcheck | tab ignoring missing output line for total leaks
[task 2022-05-17T06:51:42.317Z] 06:51:42     INFO - leakcheck | Processing leak log file C:\Users\task_165276531215901\AppData\Local\Temp\tmpgc6pqtky\runtests_leaks_6824_tab_pid1944.log
[task 2022-05-17T06:51:42.317Z] 06:51:42     INFO - TEST-PASS | leakcheck | tab no leaks detected!

:ng, since you are the author of the regressor, bug 1760843, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(na-g)
Has Regression Range: --- → yes

Set release status flags based on info from the regressing bug 1760843

Set release status flags based on info from the regressing bug 1760843

We haven't had a failure in a while now.. I am going to close this for now.

Status: NEW → RESOLVED
Closed: 2 years ago
Flags: needinfo?(na-g)
Resolution: --- → WORKSFORME
Duplicate of this bug: 1798556
Status: RESOLVED → REOPENED
Resolution: WORKSFORME → ---

For the record, this still happens on all branches. But because wpt-meta expects a crash here, the test is marked green despite crashing.

Fixed by bug 1800213.

Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Depends on: 1800213
Resolution: --- → FIXED
Assignee: nobody → apehrson
Target Milestone: --- → 112 Branch
You need to log in before you can comment on or make changes to this bug.