Closed Bug 1676142 Opened 4 years ago Closed 1 year ago

Intermittent Assertion failure: IsCurrentThreadRunningWorker(), at /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp:169

Categories

(Core :: DOM: Service Workers, defect, P3)

defect

Tracking

()

RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: assertion, intermittent-failure, Whiteboard: [bugmon:bisected,confirmed])

Attachments

(1 file)

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


[task 2020-11-09T12:37:26.288Z] 12:37:26     INFO - TEST-START | /fetch/range/sw.https.window.html
[task 2020-11-09T12:37:26.293Z] 12:37:26     INFO - Closing window 49
[task 2020-11-09T12:37:26.703Z] 12:37:26     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.705Z] 12:37:26     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2020-11-09T12:37:26.707Z] 12:37:26     INFO - PID 3772 | [Child 9172, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWind[oParw ||ent  m59Ow4n4er,Wind ow) faIiPlDLe d: Bfilae /cbuikldsg/rwoorkuer/checnkoutds/g]ec koW/dAom/RevNenItsN/DOMEGve:nt Targe'tHaelpReer.cpps:291
[task 2020-11-09T12:37:26.707Z] 12:37:26     INFO - PID 3772 | ult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:470
[task 2020-11-09T12:37:26.707Z] 12:37:26     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:123
[task 2020-11-09T12:37:26.726Z] 12:37:26     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.757Z] 12:37:26     INFO - PID 1968 | [Parent 4668, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1552
[task 2020-11-09T12:37:26.767Z] 12:37:26     INFO - PID 3772 | [Child 4260, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.767Z] 12:37:26     INFO - PID 3772 | [Child 4260, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.786Z] 12:37:26     INFO - PID 1968 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1604924302\appdata\local\temp\tmpfeylu5\runtests_leaks_8416_tab_pid3088.log
[task 2020-11-09T12:37:26.786Z] 12:37:26     INFO - PID 1968 | [3088, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2020-11-09T12:37:26.865Z] 12:37:26     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4280
[task 2020-11-09T12:37:26.947Z] 12:37:26     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.947Z] 12:37:26     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2020-11-09T12:37:26.966Z] 12:37:26     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.992Z] 12:37:26     INFO - PID 3772 | [Child 4260, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:26.997Z] 12:37:26     INFO - PID 3772 | [Child 4260, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.107Z] 12:37:27     INFO - PID 1968 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1604924302\appdata\local\temp\tmpfeylu5\runtests_leaks_8416_tab_pid1952.log
[task 2020-11-09T12:37:27.107Z] 12:37:27     INFO - PID 1968 | [1952, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2020-11-09T12:37:27.218Z] 12:37:27     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.243Z] 12:37:27     INFO - PID 3772 | [Child 9172, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.253Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:1040
[task 2020-11-09T12:37:27.255Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:1040
[task 2020-11-09T12:37:27.260Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:1040
[task 2020-11-09T12:37:27.262Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:1040
[task 2020-11-09T12:37:27.281Z] 12:37:27     INFO - PID 1968 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1604924302\appdata\local\temp\tmpfeylu5\runtests_leaks_8416_tab_pid5676.log
[task 2020-11-09T12:37:27.281Z] 12:37:27     INFO - PID 1968 | [5676, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2020-11-09T12:37:27.281Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4280
[task 2020-11-09T12:37:27.300Z] 12:37:27     INFO - PID 3772 | [Child 9172, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.446Z] 12:37:27     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2710
[task 2020-11-09T12:37:27.455Z] 12:37:27     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2710
[task 2020-11-09T12:37:27.465Z] 12:37:27     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2710
[task 2020-11-09T12:37:27.465Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1603
[task 2020-11-09T12:37:27.470Z] 12:37:27     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2710
[task 2020-11-09T12:37:27.600Z] 12:37:27     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.600Z] 12:37:27     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2020-11-09T12:37:27.600Z] 12:37:27     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:470
[task 2020-11-09T12:37:27.601Z] 12:37:27     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:123
[task 2020-11-09T12:37:27.611Z] 12:37:27     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.613Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3158
[task 2020-11-09T12:37:27.615Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3158
[task 2020-11-09T12:37:27.615Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3158
[task 2020-11-09T12:37:27.615Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3158
[task 2020-11-09T12:37:27.645Z] 12:37:27     INFO - PID 3772 | [Child 4260, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.645Z] 12:37:27     INFO - PID 3772 | [Child 4260, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:27.704Z] 12:37:27     INFO - PID 3772 | [Child 9172, MediaPDecoder #1] WARNING: NS_ENSURE_SUCCESS(rv, 0) failed with result 0x80004004 (NS_ERROR_ABORT): file /builds/worker/checkouts/gecko/dom/media/wave/WaveDemuxer.cpp:537
[task 2020-11-09T12:37:27.704Z] 12:37:27     INFO - PID 3772 | [Child 9172, MediaDecoderStateMachine #1] WARNING: Decoder=1789fc72400 state=DECODING_METADATA Decode metadata failed, shutting down decoder: file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:376
[task 2020-11-09T12:37:27.704Z] 12:37:27     INFO - PID 3772 | [Child 9172, MediaDecoderStateMachine #1] WARNING: Decoder=1789fc72400 Decode error: NS_ERROR_DOM_MEDIA_METADATA_ERR (0x806e0006): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3471
[task 2020-11-09T12:37:27.723Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4282
[task 2020-11-09T12:37:27.742Z] 12:37:27     INFO - PID 1968 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2020-11-09T12:37:27.782Z] 12:37:27     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1132
[task 2020-11-09T12:37:28.162Z] 12:37:28     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.162Z] 12:37:28     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.181Z] 12:37:28     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:28.183Z] 12:37:28     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2020-11-09T12:37:28.183Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:470
[task 2020-11-09T12:37:28.183Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:123
[task 2020-11-09T12:37:28.188Z] 12:37:28     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:28.218Z] 12:37:28     INFO - PID 3772 | [Child 9172, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:28.218Z] 12:37:28     INFO - PID 3772 | [Child 9172, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:577
[task 2020-11-09T12:37:28.402Z] 12:37:28     INFO - .........
[task 2020-11-09T12:37:28.402Z] 12:37:28     INFO - TEST-OK | /fetch/range/sw.https.window.html | took 2113ms
[task 2020-11-09T12:37:28.402Z] 12:37:28     INFO - Restarting browser for new test group
[task 2020-11-09T12:37:28.422Z] 12:37:28     INFO - PID 3772 | 1604925448413	Marionette	INFO	Stopped listening on port 57721
[task 2020-11-09T12:37:28.444Z] 12:37:28     INFO - PID 3772 | [Child 4908, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.444Z] 12:37:28     INFO - PID 3772 | [Child 4908, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.449Z] 12:37:28     INFO - PID 3772 | [Child 4908, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.451Z] 12:37:28     INFO - PID 3772 | [Child 4908, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.453Z] 12:37:28     INFO - PID 3772 | [Child 4908, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:28.512Z] 12:37:28     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Suboptimal indexes for the SQL statement 0x235174aad00 (http://mzl.la/1FuID0j).: file /builds/worker/checkouts/gecko/storage/mozStoragePrivateHelpers.cpp:113
[task 2020-11-09T12:37:28.563Z] 12:37:28     INFO - PID 3772 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-09T12:37:28.563Z] 12:37:28     INFO - PID 3772 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-09T12:37:28.568Z] 12:37:28     INFO - PID 3772 | [Parent 5944, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:497
[task 2020-11-09T12:37:28.572Z] 12:37:28     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2020-11-09T12:37:28.574Z] 12:37:28     INFO - PID 3772 | [Parent 5944, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2020-11-09T12:37:28.579Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:470
[task 2020-11-09T12:37:28.579Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:123
[task 2020-11-09T12:37:28.579Z] 12:37:28     INFO - PID 3772 | [Child 9172, Worker Launcher] WARNING: Not resolving response because actor is dead.: file /builds/worker/workspace/obj-build/ipc/ipdl/PRemoteWorkerChild.cpp:475
[task 2020-11-09T12:37:28.598Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:470
[task 2020-11-09T12:37:28.598Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:91
[task 2020-11-09T12:37:28.598Z] 12:37:28     INFO - PID 3772 | [Parent 5944, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:123
[task 2020-11-09T12:37:28.643Z] 12:37:28     INFO - PID 3772 | [Child 9172, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:291
[task 2020-11-09T12:37:28.661Z] 12:37:28     INFO - PID 3772 | Assertion failure: IsCurrentThreadRunningWorker(), at /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp:169
[task 2020-11-09T12:37:28.683Z] 12:37:28     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/ThirdPartyUtil.cpp:402
[task 2020-11-09T12:37:28.683Z] 12:37:28     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1132
[task 2020-11-09T12:37:28.723Z] 12:37:28     INFO - PID 3772 | [WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2020-11-09T12:37:28.723Z] 12:37:28     INFO - PID 3772 | [WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2020-11-09T12:37:28.793Z] 12:37:28     INFO - STDOUT: Initializing stack-fixing for the first stack frame, this may take a while...
[task 2020-11-09T12:37:29.557Z] 12:37:29     INFO - PID 1968 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1604924302\appdata\local\temp\tmpfeylu5\runtests_leaks_8416_tab_pid3776.log
[task 2020-11-09T12:37:29.557Z] 12:37:29     INFO - PID 1968 | [3776, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2020-11-09T12:37:30.099Z] 12:37:30     INFO - PID 1968 | 1604925450085	Marionette	INFO	Listening on port 57771
[task 2020-11-09T12:37:30.500Z] 12:37:30     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4280
[task 2020-11-09T12:37:30.500Z] 12:37:30     INFO - PID 1968 | [Parent 4668, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4280
[task 2020-11-09T12:37:30.746Z] 12:37:30     INFO - TEST-START | /svg/types/elements/SVGGeometryElement-rect.svg
[task 2020-11-09T12:37:31.147Z] 12:37:31     INFO - ..
[task 2020-11-09T12:37:31.147Z] 12:37:31     INFO - TEST-OK | /svg/types/elements/SVGGeometryElement-rect.svg | took 402ms
[task 2020-11-09T12:37:31.148Z] 12:37:31     INFO - Restarting browser for new test group
[task 2020-11-09T12:37:31.167Z] 12:37:31     INFO - PID 1968 | 1604925451156	Marionette	INFO	Stopped listening on port 57771
[task 2020-11-09T12:37:31.205Z] 12:37:31     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-09T12:37:31.205Z] 12:37:31     INFO - PID 1968 | [Child 5676, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40```

FWIW, I see many warning messages "!mHasOrHasHadOwnerWindow || mOwnerWindow" in this log, also right before the assertion happens. This message has been introduced by bug 1455020. Any hints, baku?

Flags: needinfo?(amarchesini)
Attached file testcase.zip

I managed to get a testcase for this issue.

Steps to reproduce using ffpuppet (https://github.com/MozillaSecurity/ffpuppet):

  1. Unzip testcase.zip
  2. python -m http.server 8888
  3. python -m ffpuppet -p prefs --xvfb ~/builds/mc-debug/firefox -u http://localhost:8888/testcase.html -d
    #0 0x7f1ce1f99165 in mozilla::dom::(anonymous namespace)::ExtendableEventKeepAliveHandler::Cleanup() /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp:169:5
    #1 0x7f1ce1f98bf3 in ~ExtendableEventKeepAliveHandler /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp:166:40
    #2 0x7f1ce1f98bf3 in mozilla::dom::(anonymous namespace)::ExtendableEventKeepAliveHandler::Release() /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerOp.cpp:223:1
    #3 0x7f1ce1f545ce in Release /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:50:40
    #4 0x7f1ce1f545ce in Release /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:381:36
    #5 0x7f1ce1f545ce in ~RefPtr /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:81:7
    #6 0x7f1ce1f545ce in ~ExtendableEvent /builds/worker/workspace/obj-build/dist/include/mozilla/dom/ServiceWorkerEvents.h:101:3
    #7 0x7f1ce1f545ce in mozilla::dom::FetchEvent::~FetchEvent() /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerEvents.cpp:124:25
    #8 0x7f1ce1f545f0 in mozilla::dom::FetchEvent::~FetchEvent() /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerEvents.cpp:124:25
    #9 0x7f1cdd92ad4d in SuspectAfterShutdown(void*, nsCycleCollectionParticipant*, nsCycleCollectingAutoRefCnt*, bool*) /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3753:12
    #10 0x7f1ce0d63c3e in decr<&NS_CycleCollectorSuspect3> /builds/worker/workspace/obj-build/dist/include/nsISupportsImpl.h:275:7
    #11 0x7f1ce0d63c3e in decr<&NS_CycleCollectorSuspect3> /builds/worker/workspace/obj-build/dist/include/nsISupportsImpl.h:262:12
    #12 0x7f1ce0d63c3e in mozilla::dom::Event::Release() /builds/worker/checkouts/gecko/dom/events/Event.cpp:129:1
    #13 0x7f1ce1f555e4 in Release /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerEvents.cpp:1015:1
    #14 0x7f1ce1f555e4 in mozilla::dom::FetchEvent::Release() /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerEvents.cpp:971:1
    #15 0x7f1cdd91a1fe in ~nsCOMPtr_base /builds/worker/workspace/obj-build/dist/include/nsCOMPtr.h:328:7
    #16 0x7f1cdd91a1fe in mozilla::SegmentedVector<nsCOMPtr<nsISupports>, 4096ul, mozilla::MallocAllocPolicy>::SegmentImpl<509ul>::~SegmentImpl() /builds/worker/workspace/obj-build/dist/include/mozilla/SegmentedVector.h:78:21
    #17 0x7f1cdd91a002 in mozilla::SegmentedVector<nsCOMPtr<nsISupports>, 4096ul, mozilla::MallocAllocPolicy>::PopLastN(unsigned int) /builds/worker/workspace/obj-build/dist/include/mozilla/SegmentedVector.h:246:14
    #18 0x7f1cdd8fe4d7 in mozilla::dom::DeferredFinalizerImpl<nsISupports>::DeferredFinalize(unsigned int, void*) /builds/worker/workspace/obj-build/dist/include/mozilla/dom/BindingUtils.h:2734:15
    #19 0x7f1cdd8fecfb in mozilla::IncrementalFinalizeRunnable::ReleaseNow(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSRuntime.cpp:1633:17
    #20 0x7f1cdd8ff34d in mozilla::CycleCollectedJSRuntime::FinalizeDeferredThings(mozilla::CycleCollectedJSContext::DeferredFinalizeType) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSRuntime.cpp:1709:24
    #21 0x7f1cdd8fc97d in mozilla::CycleCollectedJSRuntime::OnGC(JSContext*, JSGCStatus, JS::GCReason) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSRuntime.cpp:1779:7
    #22 0x7f1ce43417ed in js::gc::GCRuntime::maybeCallGCCallback(JSGCStatus, JS::GCReason) /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:7222:3
    #23 0x7f1ce434227e in ~AutoCallGCCallbacks /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:7201:32
    #24 0x7f1ce434227e in js::gc::GCRuntime::gcCycle(bool, js::SliceBudget const&, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:7298:1
    #25 0x7f1ce434329b in js::gc::GCRuntime::collect(bool, js::SliceBudget const&, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:7495:9
    #26 0x7f1ce4307562 in js::gc::GCRuntime::gc(JSGCInvocationKind, JS::GCReason) /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:7575:3
    #27 0x7f1ce3de77ef in JSRuntime::destroyRuntime() /builds/worker/checkouts/gecko/js/src/vm/Runtime.cpp:286:8
    #28 0x7f1ce3d24791 in js::DestroyContext(JSContext*) /builds/worker/checkouts/gecko/js/src/vm/JSContext.cpp:214:7
    #29 0x7f1cdd8f2ea4 in mozilla::CycleCollectedJSContext::~CycleCollectedJSContext() /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:109:3
    #30 0x7f1ce1c998fd in ~WorkerJSContext /builds/worker/checkouts/gecko/dom/workers/RuntimeService.cpp:887:3
    #31 0x7f1ce1c998fd in operator() /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:463:5
    #32 0x7f1ce1c998fd in reset /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:305:7
    #33 0x7f1ce1c998fd in ~UniquePtr /builds/worker/workspace/obj-build/dist/include/mozilla/UniquePtr.h:253:18
    #34 0x7f1ce1c998fd in mozilla::dom::workerinternals::(anonymous namespace)::WorkerThreadPrimaryRunnable::Run() /builds/worker/checkouts/gecko/dom/workers/RuntimeService.cpp:2252:5
    #35 0x7f1cdda06003 in nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1152:16
    #36 0x7f1cdda0c36a in NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10
    #37 0x7f1cde32bedd in mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:302:20
    #38 0x7f1cde296643 in MessageLoop::RunInternal() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:335:10
    #39 0x7f1cde29655d in RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:328:3
    #40 0x7f1cde29655d in MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:310:3
    #41 0x7f1cdda02726 in nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:391:10
    #42 0x7f1cf41ebcdb in _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5
    #43 0x7f1cf489f608 in start_thread /build/glibc-eX1tMB/glibc-2.31/nptl/pthread_create.c:477:8
Blocks: domino
Keywords: bugmon
Whiteboard: [bugmon:confirm]

Bugmon Analysis:
Verified bug as reproducible on mozilla-central 20210203214546-ca7a3f92939d.
Failed to bisect testcase (Testcase reproduces on start build!):

Start: 82d250cf8fabf9a76db02499407f8d11ce12f0f2 (20200206051330)
End: 7daa0ef24433e58574f3ea0008ad9abb3485f4c6 (20201109035154)
BuildFlags: BuildFlags(asan=False, tsan=False, debug=True, fuzzing=False, coverage=False, valgrind=False)

Whiteboard: [bugmon:confirm] → [bugmon:bisected,confirmed]

Now that we have a test case, we should look at it again.

Severity: normal → S3
Flags: needinfo?(amarchesini)
Priority: P5 → P3

Bugmon Analysis
Testcase crashes using the initial build (mozilla-central 20210130194115-93831b5983e9) but not with tip (mozilla-central 20220129091708-7f00dabac085.)
The bug appears to have been fixed in the following build range:

Start: e960e654cbc9f60ce79eb1535fd6ec4e3acc2029 (20220125100058)
End: 1057ad7f4c4e7da1e9bb96553a49326086bafea8 (20220125123006)
Pushlog: https://hg.mozilla.org/mozilla-unified/pushloghtml?fromchange=e960e654cbc9f60ce79eb1535fd6ec4e3acc2029&tochange=1057ad7f4c4e7da1e9bb96553a49326086bafea8
Removing bugmon keyword as no further action possible. Please review the bug and re-add the keyword for further analysis.

Keywords: bugmon

(In reply to Bugmon [:jkratzer for issues] from comment #58)

Bugmon Analysis
Testcase crashes using the initial build (mozilla-central 20210130194115-93831b5983e9) but not with tip (mozilla-central 20220129091708-7f00dabac085.)
The bug appears to have been fixed in the following build range:

Start: e960e654cbc9f60ce79eb1535fd6ec4e3acc2029 (20220125100058)
End: 1057ad7f4c4e7da1e9bb96553a49326086bafea8 (20220125123006)
Pushlog: https://hg.mozilla.org/mozilla-unified/pushloghtml?fromchange=e960e654cbc9f60ce79eb1535fd6ec4e3acc2029&tochange=1057ad7f4c4e7da1e9bb96553a49326086bafea8
Removing bugmon keyword as no further action possible. Please review the bug and re-add the keyword for further analysis.

I am not able to see what in that range helped, but it seems to work now.

Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: