1779699 - Intermittent Assertion failure: valid, at /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:4408

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2022-07-15T04:26:48.144Z] 04:26:48     INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_start.js
[task 2022-07-15T04:26:48.300Z] 04:26:48     INFO - GECKO(1626) | [Parent 1626, 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:1876
[task 2022-07-15T04:26:48.300Z] 04:26:48     INFO - GECKO(1626) | [Parent 1626, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1876
[task 2022-07-15T04:26:48.317Z] 04:26:48     INFO - GECKO(1626) | [Parent 1626: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f36a1d50800 == 11 [pid = 1626] [id = 175]
<...>
[task 2022-07-15T04:26:54.159Z] 04:26:54     INFO - GECKO(1626) | [Child 2330, Main Thread] WARNING: IPC message 'PContent::Msg_AsyncMessage' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2022-07-15T04:26:54.161Z] 04:26:54     INFO - GECKO(1626) | console.error: ({})
[task 2022-07-15T04:26:54.163Z] 04:26:54     INFO - GECKO(1626) | [Exception... "Unexpected error"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: resource://devtools/server/startup/content-process.jsm :: onDestroy :: line 71"  data: no]: onDestroy@resource://devtools/server/startup/content-process.jsm:71:8
[task 2022-07-15T04:26:54.164Z] 04:26:54     INFO - GECKO(1626) | _emit@resource://devtools/shared/event-emitter.js:242:32
[task 2022-07-15T04:26:54.166Z] 04:26:54     INFO - GECKO(1626) | emit@resource://devtools/shared/event-emitter.js:186:18
[task 2022-07-15T04:26:54.166Z] 04:26:54     INFO - GECKO(1626) | emit@resource://devtools/shared/event-emitter.js:330:18
[task 2022-07-15T04:26:54.167Z] 04:26:54     INFO - GECKO(1626) | destroy@resource://devtools/server/actors/targets/content-process.js:228:12
[task 2022-07-15T04:26:54.168Z] 04:26:54     INFO - GECKO(1626) | module.exports/proto.destroy@resource://devtools/server/actors/targets/target-actor-mixin.js:253:30
[task 2022-07-15T04:26:54.169Z] 04:26:54     INFO - GECKO(1626) | [Parent 1626, 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:1876
[task 2022-07-15T04:26:54.171Z] 04:26:54     INFO - GECKO(1626) | [Child 2330, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:1561
[task 2022-07-15T04:26:54.172Z] 04:26:54     INFO - GECKO(1626) | [Child 2330, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:2038
[task 2022-07-15T04:26:54.177Z] 04:26:54     INFO - GECKO(1626) | [Child 2330, DOM Worker] WARNING: '!mWorkerPrivate->AddWorkerRef(this, aStatus)', file /builds/worker/checkouts/gecko/dom/workers/WorkerRef.cpp:79
[task 2022-07-15T04:26:54.181Z] 04:26:54     INFO - GECKO(1626) | Assertion failure: valid, at /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:4408
[task 2022-07-15T04:26:54.190Z] 04:26:54     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2022-07-15T04:27:07.973Z] 04:27:07     INFO - GECKO(1626) | #01: mozilla::dom::WorkerPrivate::AssertValidSyncLoop(nsIEventTarget*) [dom/workers/WorkerPrivate.cpp:4408]
[task 2022-07-15T04:27:07.975Z] 04:27:07     INFO - GECKO(1626) | #02: mozilla::dom::workerinternals::loader::WorkerScriptLoader::DispatchMaybeMoveToLoadedList(JS::loader::ScriptLoadRequest*) [dom/workers/ScriptLoader.cpp:888]
[task 2022-07-15T04:27:07.977Z] 04:27:07     INFO - GECKO(1626) | #03: mozilla::dom::workerinternals::loader::WorkerScriptLoader::OnStreamComplete(JS::loader::ScriptLoadRequest*, nsresult) [dom/workers/ScriptLoader.cpp:632]
[task 2022-07-15T04:27:07.979Z] 04:27:07     INFO - GECKO(1626) | #04: mozilla::dom::workerinternals::loader::CacheLoadHandler::OnStreamComplete(nsIStreamLoader*, nsISupports*, nsresult, unsigned int, unsigned char const*) [dom/workers/loader/CacheLoadHandler.cpp:474]
[task 2022-07-15T04:27:07.980Z] 04:27:07     INFO - GECKO(1626) | #05: mozilla::net::nsStreamLoader::OnStopRequest(nsIRequest*, nsresult) [netwerk/base/nsStreamLoader.cpp:87]
[task 2022-07-15T04:27:07.982Z] 04:27:07     INFO - GECKO(1626) | #06: nsInputStreamPump::OnStateStop() [netwerk/base/nsInputStreamPump.cpp:659]
[task 2022-07-15T04:27:07.983Z] 04:27:07     INFO - GECKO(1626) | #07: nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) [netwerk/base/nsInputStreamPump.cpp:391]
[task 2022-07-15T04:27:07.984Z] 04:27:07     INFO - GECKO(1626) | #08: {virtual override thunk({offset(-8)}, nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*))} [/builds/worker/workspace/build/application/firefox/libxul.so + 0x4b0be3b]
[task 2022-07-15T04:27:07.986Z] 04:27:07     INFO - GECKO(1626) | #09: already_AddRefed<mozilla::CancelableRunnable> NS_NewCancelableRunnableFunction<CallbackHolder::CallbackHolder(nsIAsyncInputStream*, nsIInputStreamCallback*, unsigned int, nsIEventTarget*)::{lambda()#1}>(char const*, CallbackHolder::CallbackHolder(nsIAsyncInputStream*, nsIInputStreamCallback*, unsigned int, nsIEventTarget*)::{lambda()#1}&&)::FuncCancelableRunnable::Run() [xpcom/threads/nsThreadUtils.h:653]
[task 2022-07-15T04:27:07.987Z] 04:27:07     INFO - GECKO(1626) | #10: mozilla::ThrottledEventQueue::Inner::ExecuteRunnable() [xpcom/threads/ThrottledEventQueue.cpp:257]
[task 2022-07-15T04:27:07.988Z] 04:27:07     INFO - GECKO(1626) | #11: mozilla::ThrottledEventQueue::Inner::Executor::Run() [xpcom/threads/ThrottledEventQueue.cpp:82]
[task 2022-07-15T04:27:07.989Z] 04:27:07     INFO - GECKO(1626) | #12: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:539]
[task 2022-07-15T04:27:07.990Z] 04:27:07     INFO - GECKO(1626) | #13: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) [xpcom/threads/TaskController.cpp:851]
[task 2022-07-15T04:27:07.991Z] 04:27:07     INFO - GECKO(1626) | #14: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) [xpcom/threads/TaskController.cpp:683]
[task 2022-07-15T04:27:07.992Z] 04:27:07     INFO - GECKO(1626) | #15: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:461]
[task 2022-07-15T04:27:07.993Z] 04:27:07     INFO - GECKO(1626) | #16: mozilla::detail::RunnableFunction<mozilla::TaskController::InitializeInternal()::$_1>::Run() [xpcom/threads/nsThreadUtils.h:532]
[task 2022-07-15T04:27:07.994Z] 04:27:07     INFO - GECKO(1626) | #17: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1209]
[task 2022-07-15T04:27:07.995Z] 04:27:07     INFO - GECKO(1626) | #18: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:465]
[task 2022-07-15T04:27:07.997Z] 04:27:07     INFO - GECKO(1626) | #19: mozilla::dom::workerinternals::RuntimeService::Cleanup() [dom/workers/RuntimeService.cpp:1628]
[task 2022-07-15T04:27:07.998Z] 04:27:07     INFO - GECKO(1626) | #20: mozilla::dom::workerinternals::RuntimeService::Observe(nsISupports*, char const*, char16_t const*) [dom/workers/RuntimeService.cpp:1920]
[task 2022-07-15T04:27:07.999Z] 04:27:07     INFO - GECKO(1626) | #21: nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [xpcom/ds/nsObserverList.cpp:69]
[task 2022-07-15T04:27:08.004Z] 04:27:08     INFO - GECKO(1626) | #22: nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [xpcom/ds/nsObserverService.cpp:294]
[task 2022-07-15T04:27:08.005Z] 04:27:08     INFO - GECKO(1626) | #23: mozilla::AppShutdown::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) [xpcom/base/AppShutdown.cpp:374]
[task 2022-07-15T04:27:08.006Z] 04:27:08     INFO - GECKO(1626) | #24: mozilla::ShutdownXPCOM(nsIServiceManager*) [xpcom/build/XPCOMInit.cpp:600]
[task 2022-07-15T04:27:08.007Z] 04:27:08     INFO - GECKO(1626) | #25: XRE_TermEmbedding() [toolkit/xre/nsEmbedFunctions.cpp:225]
[task 2022-07-15T04:27:08.008Z] 04:27:08     INFO - GECKO(1626) | #26: mozilla::ipc::ScopedXREEmbed::Stop() [ipc/glue/ScopedXREEmbed.cpp:91]
[task 2022-07-15T04:27:08.010Z] 04:27:08     INFO - GECKO(1626) | #27: XRE_InitChildProcess(int, char**, XREChildData const*) [toolkit/xre/nsEmbedFunctions.cpp:739]
[task 2022-07-15T04:27:08.076Z] 04:27:08     INFO - GECKO(1626) | #28: content_process_main(mozilla::Bootstrap*, int, char**) [ipc/contentproc/plugin-container.cpp:58]
[task 2022-07-15T04:27:08.078Z] 04:27:08     INFO - GECKO(1626) | #29: main [browser/app/nsBrowserApp.cpp:338]
[task 2022-07-15T04:27:08.083Z] 04:27:08     INFO - GECKO(1626) | #30: __libc_start_main [/lib/x86_64-linux-gnu/libc.so.6 + 0x21b97]
[task 2022-07-15T04:27:08.085Z] 04:27:08     INFO - GECKO(1626) | #31: ??? [/builds/worker/workspace/build/application/firefox/firefox-bin + 0x449d9]
[task 2022-07-15T04:27:08.085Z] 04:27:08     INFO - GECKO(1626) | #32: ??? (???:???)
[task 2022-07-15T04:27:08.092Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 3865418CC0431CC7.9BC2ED05E8AA6943: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2022-07-15T04:27:08.093Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 3865418CC0431CC7.9BC2ED05E8AA6943: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2022-07-15T04:27:08.094Z] 04:27:08     INFO - GECKO(1626) | [Child 2285, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-15T04:27:08.095Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:162
[task 2022-07-15T04:27:08.095Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:162
[task 2022-07-15T04:27:08.100Z] 04:27:08     INFO - GECKO(1626) | console.error: "[ACTION FAILED] REQUEST_WORKERS_FAILURE: Connection closed, pending request to root, type listServiceWorkerRegistrations failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:293:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19\nlistAllServiceWorkers@resource://devtools/client/fronts/root.js:56:42\nlistAllWorkers@resource://devtools/client/fronts/root.js:114:39\nasync*listWorkers@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:160:36\nrequestWorkers/<@resource://devtools/client/aboutdebugging/src/actions/debug-targets.js:278:31\nthunk/</</<@resource://devtools/client/shared/redux/middleware/thunk.js:17:11\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nonWorkersUpdated@resource://devtools/client/aboutdebugging/src/middleware/debug-target-listener.js:28:11\n_emit@resource://devtools/shared/event-emitter.js:242:32\nemit@resource://devtools/shared/event-emitter.js:186:18\nemit@resource://devtools/shared/event-emitter.js:330:18\nonPacket@resource://devtools/shared/protocol/Front.js:342:13\nonPacket@resource://devtools/client/devtools-client.js:482:13\nsend/<@resource://devtools/shared/transport/local-transport.js:68:25\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:56:21\nsend@resource://devtools/shared/transport/local-transport.js:56:21\nsend@resource://devtools/server/devtools-server-connection.js:99:20\n_sendEvent@resource://devtools/shared/protocol/Actor.js:75:15\ninitialize/<@resource://devtools/shared/protocol/Actor.js:49:16\n_emit@resource://devtools/shared/event-emitter.js:242:32\nemit@resource://devtools/shared/event-emitter.js:186:18\nemit@resource://devtools/shared/event-emitter.js:330:18\nonChange@resource://devtools/server/actors/worker/service-worker-registration.js:65:12\nunsubscribe/<@resource://gre/modules/PushComponents.jsm:170:20\npromise callback*unsubscribe@resource://gre/modules/PushComponents.jsm:168:8\n"
[task 2022-07-15T04:27:08.108Z] 04:27:08     INFO - GECKO(1626) | console.error: "baseFrontClassDestroy@resource://devtools/shared/protocol/Front.js:107:23\npurgeRequests@resource://devtools/client/devtools-client.js:683:15\nonTransportClosed@resource://devtools/client/devtools-client.js:598:10\nclose@resource://devtools/shared/transport/local-transport.js:170:22\nclose@resource://devtools/shared/transport/local-transport.js:165:13\nclose@resource://devtools/shared/transport/local-transport.js:165:13\nclose@resource://devtools/client/devtools-client.js:131:23\nclose@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:170:24\ndisconnectRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:233:27\nthunk/</</<@resource://devtools/client/shared/redux/middleware/thunk.js:17:11\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nunwatchRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:324:15\nthunk/</</<@resource://devtools/client/shared/redux/middleware/thunk.js:17:11\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\ndestroy@resource://devtools/client/aboutdebugging/aboutdebugging.js:153:26\n@resource://devtools/client/aboutdebugging/aboutdebugging.js:190:20\n_endRemoveTab@chrome://browser/content/tabbrowser.js:4178:15\nremoveTab@chrome://browser/content/tabbrowser.js:3811:14\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:494:12\n@chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_start.js:70:9\nAsync*handleTask@chrome://mochikit/content/browser-test.js:988:26\n_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1060:18\nTester_execTest@chrome://mochikit/content/browser-test.js:1195:14\nnextTest/<@chrome://mochikit/content/browser-test.js:977:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13\n"
[task 2022-07-15T04:27:08.109Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, 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:1876
[task 2022-07-15T04:27:08.110Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, 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:1876
[task 2022-07-15T04:27:08.111Z] 04:27:08     INFO - GECKO(1626) | [Parent 1626, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1876
[task 2022-07-15T04:27:08.111Z] 04:27:08     INFO - GECKO(1626) | MEMORY STAT | vsize 11840MB | residentFast 637MB | heapAllocated 302MB
[task 2022-07-15T04:27:08.112Z] 04:27:08     INFO - TEST-OK | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_start.js | took 6653ms
[task 2022-07-15T04:27:08.113Z] 04:27:08     INFO - GECKO(1626) | [Child 2266, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-07-15T04:27:08.113Z] 04:27:08     INFO - checking window state
[task 2022-07-15T04:27:08.114Z] 04:27:08     INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_serviceworker_status.js

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1779699&startday=2022-07-11&endday=2022-07-17&tree=all

Comment 2

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

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

Comment 3

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

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