1856058 - Intermittent /mediacapture-streams/enumerateDevices-with-navigation.https.html | single tracking bug

Status: RESOLVED DUPLICATE of bug 1856037

(Core :: Audio/Video: MediaStreamGraph, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-09-29T18:22:03.885Z] 18:22:03     INFO - TEST-START | /mediacapture-streams/enumerateDevices-with-navigation.https.html
[task 2023-09-29T18:22:03.890Z] 18:22:03     INFO - Closing window 4612f7c0-eec8-4e4e-a311-fdf56c266c64
[task 2023-09-29T18:22:19.771Z] 18:22:19     INFO - PID 2134 | [Child 2166, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2023-09-29T18:22:19.772Z] 18:22:19     INFO - PID 2134 | [Child 2166, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2023-09-29T18:22:35.951Z] 18:22:35     INFO - 
[task 2023-09-29T18:22:35.951Z] 18:22:35     INFO - TEST-UNEXPECTED-TIMEOUT | /mediacapture-streams/enumerateDevices-with-navigation.https.html | enumerateDevices with navigation - Test timed out
[task 2023-09-29T18:22:35.951Z] 18:22:35     INFO - TEST-UNEXPECTED-TIMEOUT | /mediacapture-streams/enumerateDevices-with-navigation.https.html | expected OK
[task 2023-09-29T18:22:35.951Z] 18:22:35     INFO - TEST-INFO took 32066ms
[task 2023-09-29T18:22:35.954Z] 18:22:35     INFO - PID 2134 | 1696011755953	Marionette	INFO	Stopped listening on port 51712
[task 2023-09-29T18:22:35.983Z] 18:22:35     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:35.983Z] 18:22:35     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:35.986Z] 18:22:35     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:35.988Z] 18:22:35     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:35.991Z] 18:22:35     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:35.992Z] 18:22:35     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:36.000Z] 18:22:36     INFO - PID 2134 | [Child 2141, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2023-09-29T18:22:36.052Z] 18:22:36     INFO - PID 2134 | [Child 2155, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4209
[task 2023-09-29T18:22:36.053Z] 18:22:36     INFO - PID 2134 | [Child 2157, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4209
[task 2023-09-29T18:22:36.053Z] 18:22:36     INFO - PID 2134 | [Child 2158, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4209
[task 2023-09-29T18:22:36.054Z] 18:22:36     INFO - PID 2134 | [Child 2166, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4209
[task 2023-09-29T18:22:36.079Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-09-29T18:22:36.096Z] 18:22:36     INFO - PID 2134 | [Child 2166, Main Thread] WARNING: IPC message 'PContent::Msg_StoreUserInteractionAsPermission' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2023-09-29T18:22:36.102Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-09-29T18:22:36.102Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-09-29T18:22:36.102Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-09-29T18:22:36.104Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] Send(msgname=PTexture::Msg___delete__) Channel closing: too late to send, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1923
[task 2023-09-29T18:22:36.105Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] Send(msgname=PTexture::Msg___delete__) Channel closing: too late to send, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1923
[task 2023-09-29T18:22:36.106Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] Send(msgname=PTexture::Msg___delete__) Channel closing: too late to send, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1923
[task 2023-09-29T18:22:36.115Z] 18:22:36     INFO - PID 2134 | [Child 2157, Main Thread] WARNING: Attempt to __delete__ missing or closed actor: file /builds/worker/workspace/obj-build/ipc/ipdl/PWebGLChild.cpp:130
[task 2023-09-29T18:22:36.115Z] 18:22:36     INFO - PID 2134 | [Child 2157, Main Thread] WARNING: Attempt to __delete__ missing or closed actor: file /builds/worker/workspace/obj-build/ipc/ipdl/PWebGLChild.cpp:130
[task 2023-09-29T18:22:36.115Z] 18:22:36     INFO - PID 2134 | [Child 2166, Main Thread] WARNING: Attempt to __delete__ missing or closed actor: file /builds/worker/workspace/obj-build/ipc/ipdl/PWebGLChild.cpp:130
[task 2023-09-29T18:22:36.115Z] 18:22:36     INFO - PID 2134 | [Child 2166, Main Thread] WARNING: Attempt to __delete__ missing or closed actor: file /builds/worker/workspace/obj-build/ipc/ipdl/PWebGLChild.cpp:130
[task 2023-09-29T18:22:36.364Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.000006s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.365Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.000086s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.366Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.000111s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.367Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: cache: '0.000147s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.367Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: sdb: '0.000181s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.368Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: fs: '0.000214s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.368Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: ls: '0.000267s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.369Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.000347s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.370Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.000421s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.371Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.000441s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.371Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.000656s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.372Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.000712s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.372Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.000722s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.373Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.002230s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.373Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.002250s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.373Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: idb: '0.002259s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.374Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: cache: '0.002327s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.374Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: sdb: '0.002338s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.374Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: fs: '0.002345s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.375Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: ls: '0.002358s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.375Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.002744s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.375Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.002949s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.376Z] 18:22:36     INFO - PID 2134 | [Parent 2134, IPDL Background] WARNING: quota manager shutdown step: '0.002961s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2211
[task 2023-09-29T18:22:36.462Z] 18:22:36     INFO - PID 2134 | [Utility 2145, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2023-09-29T18:22:36.905Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-09-29T18:22:36.906Z] 18:22:36     INFO - PID 2134 | [Parent 2134, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-09-29T18:22:37.097Z] 18:22:37     INFO - Browser exited with return code 0
[task 2023-09-29T18:22:37.098Z] 18:22:37     INFO - PROCESS LEAKS /var/folders/pg/b483c4nn7qx88z7b70hpknvm000014/T/tmp2z8p_o3n/runtests_leaks_1092.log
[task 2023-09-29T18:22:37.098Z] 18:22:37     INFO - leakcheck | Processing log file /var/folders/pg/b483c4nn7qx88z7b70hpknvm000014/T/tmp2z8p_o3n/runtests_leaks_1092.log for scope /mediacapture-streams
[task 2023-09-29T18:22:37.098Z] 18:22:37     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 2100 bytes
[task 2023-09-29T18:22:37.099Z] 18:22:37     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2023-09-29T18:22:37.099Z] 18:22:37     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2023-09-29T18:22:37.099Z] 18:22:37     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2023-09-29T18:22:37.099Z] 18:22:37     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2023-09-29T18:22:37.100Z] 18:22:37     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2023-09-29T18:22:37.100Z] 18:22:37     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2023-09-29T18:22:37.100Z] 18:22:37     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2023-09-29T18:22:37.100Z] 18:22:37     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2023-09-29T18:22:37.101Z] 18:22:37     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2023-09-29T18:22:37.101Z] 18:22:37     INFO - leakcheck | Processing leak log file /var/folders/pg/b483c4nn7qx88z7b70hpknvm000014/T/tmp2z8p_o3n/runtests_leaks_1092.log
[task 2023-09-29T18:22:37.103Z] 18:22:37     INFO - TEST-PASS | leakcheck | default no leaks detected!