Closed Bug 1750062 Opened 3 years ago Closed 2 months ago

Intermittent LeakSanitizer | leak at NS_NewRunnableFunction, mozilla::dom::RemoteWorkerChild::ShutdownOnWorker, operator, std::_Function_handler

Categories

(Core :: DOM: Workers, defect)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 obsolete file)

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


[task 2022-01-13T17:38:36.210Z] 17:38:36     INFO - TEST-START | dom/serviceworkers/test/test_xslt.html
[task 2022-01-13T17:38:36.395Z] 17:38:36     INFO - GECKO(8501) | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:36.892Z] 17:38:36     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:36.892Z] 17:38:36     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:36.892Z] 17:38:36     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:36.892Z] 17:38:36     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:36.892Z] 17:38:36     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:36.892Z] 17:38:36     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:37.191Z] 17:38:37     INFO - GECKO(8501) | Set request mode
[task 2022-01-13T17:38:37.459Z] 17:38:37     INFO - GECKO(8501) | JavaScript error: , line 0: NetworkError: AutoCancel
[task 2022-01-13T17:38:37.976Z] 17:38:37     INFO - GECKO(8501) | MEMORY STAT | vsize 20982730MB | residentFast 799MB
[task 2022-01-13T17:38:38.043Z] 17:38:38     INFO - TEST-OK | dom/serviceworkers/test/test_xslt.html | took 1828ms
[task 2022-01-13T17:38:38.220Z] 17:38:38     INFO - TEST-START | Shutdown
[task 2022-01-13T17:38:38.238Z] 17:38:38     INFO - Passed:  1268
[task 2022-01-13T17:38:38.243Z] 17:38:38     INFO - Failed:  0
[task 2022-01-13T17:38:38.245Z] 17:38:38     INFO - Todo:    26
[task 2022-01-13T17:38:38.246Z] 17:38:38     INFO - Mode:    e10s
[task 2022-01-13T17:38:38.248Z] 17:38:38     INFO - Slowest: 73257ms - /tests/dom/serviceworkers/test/test_third_party_iframes.html
[task 2022-01-13T17:38:38.253Z] 17:38:38     INFO - SimpleTest FINISHED
[task 2022-01-13T17:38:38.255Z] 17:38:38     INFO - TEST-INFO | Ran 1 Loops
[task 2022-01-13T17:38:38.256Z] 17:38:38     INFO - SimpleTest FINISHED
[task 2022-01-13T17:38:38.602Z] 17:38:38     INFO - GECKO(8501) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2022-01-13T17:38:38.716Z] 17:38:38     INFO - GECKO(8501) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2022-01-13T17:38:38.718Z] 17:38:38     INFO - GECKO(8501) | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2022-01-13T17:38:38.794Z] 17:38:38     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:38.794Z] 17:38:38     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:38.794Z] 17:38:38     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:38.795Z] 17:38:38     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:38.796Z] 17:38:38     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:38.797Z] 17:38:38     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:38.824Z] 17:38:38     INFO - GECKO(8501) | 1642095518823	Marionette	TRACE	Received observer notification quit-application
[task 2022-01-13T17:38:38.829Z] 17:38:38     INFO - GECKO(8501) | 1642095518824	Marionette	INFO	Stopped listening on port 2828
[task 2022-01-13T17:38:38.831Z] 17:38:38     INFO - GECKO(8501) | 1642095518824	Marionette	DEBUG	Marionette stopped listening
[task 2022-01-13T17:38:39.068Z] 17:38:39     INFO - GECKO(8501) | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-01-13T17:38:39.129Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.130Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.150Z] 17:38:39     INFO - GECKO(8501) | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-01-13T17:38:39.342Z] 17:38:39     INFO - GECKO(8501) | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-01-13T17:38:39.347Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.349Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.358Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.368Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.368Z] 17:38:39     INFO - GECKO(8501) | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-13T17:38:39.896Z] 17:38:39     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:39.897Z] 17:38:39     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:39.899Z] 17:38:39     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:39.901Z] 17:38:39     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:39.902Z] 17:38:39     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:39.904Z] 17:38:39     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:43.812Z] 17:38:43     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:43.813Z] 17:38:43     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:43.814Z] 17:38:43     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:43.814Z] 17:38:43     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:43.815Z] 17:38:43     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:43.815Z] 17:38:43     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.115Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.115Z] 17:38:44     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:44.116Z] 17:38:44     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:44.116Z] 17:38:44     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:44.117Z] 17:38:44     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:44.117Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.328Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.328Z] 17:38:44     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:44.329Z] 17:38:44     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:44.329Z] 17:38:44     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:44.330Z] 17:38:44     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:44.330Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.371Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.371Z] 17:38:44     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:44.372Z] 17:38:44     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:44.372Z] 17:38:44     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:44.372Z] 17:38:44     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:44.372Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.395Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.395Z] 17:38:44     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:44.396Z] 17:38:44     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:44.396Z] 17:38:44     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:44.397Z] 17:38:44     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:44.398Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.493Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.494Z] 17:38:44     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:44.495Z] 17:38:44     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:44.495Z] 17:38:44     INFO - GECKO(8501) |      13        416 nsComponentManagerImpl
[task 2022-01-13T17:38:44.496Z] 17:38:44     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:44.497Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.573Z] 17:38:44     INFO - GECKO(8501) | Waiting in WillDestroyCurrentMessageLoop for pid 8622
[task 2022-01-13T17:38:44.678Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:44.679Z] 17:38:44     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:44.679Z] 17:38:44     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:44.680Z] 17:38:44     INFO - GECKO(8501) |      55       1648 nsComponentManagerImpl
[task 2022-01-13T17:38:44.680Z] 17:38:44     INFO - GECKO(8501) |       2        288 libfontconfig.so
[task 2022-01-13T17:38:44.681Z] 17:38:44     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:48.380Z] 17:38:48     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:48.381Z] 17:38:48     INFO - GECKO(8501) | Suppressions used:
[task 2022-01-13T17:38:48.382Z] 17:38:48     INFO - GECKO(8501) |   count      bytes template
[task 2022-01-13T17:38:48.383Z] 17:38:48     INFO - GECKO(8501) |      10        312 nsComponentManagerImpl
[task 2022-01-13T17:38:48.384Z] 17:38:48     INFO - GECKO(8501) |     633      18083 libfontconfig.so
[task 2022-01-13T17:38:48.384Z] 17:38:48     INFO - GECKO(8501) |       9        100 libglib-2.0.so
[task 2022-01-13T17:38:48.385Z] 17:38:48     INFO - GECKO(8501) |       2        416 mozJSComponentLoader
[task 2022-01-13T17:38:48.386Z] 17:38:48     INFO - GECKO(8501) |       2         48 XPCWrappedNativeJSOps
[task 2022-01-13T17:38:48.386Z] 17:38:48     INFO - GECKO(8501) | -----------------------------------------------------
[task 2022-01-13T17:38:48.607Z] 17:38:48     INFO - TEST-INFO | Main app process: exit 0
[task 2022-01-13T17:38:48.607Z] 17:38:48     INFO - TEST-INFO | LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2022-01-13T17:38:48.607Z] 17:38:48     INFO - TEST-INFO | LeakSanitizer | This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2022-01-13T17:38:48.607Z] 17:38:48    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at NS_NewRunnableFunction, mozilla::dom::RemoteWorkerChild::ShutdownOnWorker, operator, std::_Function_handler
[task 2022-01-13T17:38:48.607Z] 17:38:48    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at detail::ProxyRelease, ProxyRelease, NS_ProxyRelease, operator
[task 2022-01-13T17:38:48.607Z] 17:38:48    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::SupportsThreadSafeWeakPtr, mozilla::dom::RemoteWorkerChild::RemoteWorkerChild, mozilla::ipc::BackgroundChildImpl::AllocPRemoteWorkerChild, mozilla::ipc::PBackgroundChild::OnMessageReceived
[task 2022-01-13T17:38:48.607Z] 17:38:48    ERROR - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at mozilla::ipc::BackgroundChildImpl::AllocPRemoteWorkerChild, mozilla::ipc::PBackgroundChild::OnMessageReceived, mozilla::ipc::MessageChannel::DispatchAsyncMessage, mozilla::ipc::MessageChannel::DispatchMessage
[task 2022-01-13T17:38:48.608Z] 17:38:48     INFO - runtests.py | Application ran for: 0:03:56.951886
Summary: Intermittent LeakSanitizer | leak at NS_NewRunnableFunction, mozilla::dom::RemoteWorkerChild::ShutdownOnWorker, operator, std::_Function_handler → Intermittent [tier 2] LeakSanitizer | leak at NS_NewRunnableFunction, mozilla::dom::RemoteWorkerChild::ShutdownOnWorker, operator, std::_Function_handler

This looks like a leak related to a RemoteWorkerChild runnable.

Component: General → DOM: Workers
Summary: Intermittent [tier 2] LeakSanitizer | leak at NS_NewRunnableFunction, mozilla::dom::RemoteWorkerChild::ShutdownOnWorker, operator, std::_Function_handler → Intermittent LeakSanitizer | leak at NS_NewRunnableFunction, mozilla::dom::RemoteWorkerChild::ShutdownOnWorker, operator, std::_Function_handler
Flags: needinfo?(jstutte)
Blocks: LSan
See Also: → 1749068

There have been 29 failures in the last 7 days.

Happens on:

  • linux1804-64-asan-qr opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=377897290&repo=autoland&lineNumber=6759

There have been 30 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • linux1804-64-asan-qr

There have been 42 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • linux1804-64-asan-qr
Whiteboard: [stockwell unknown][stockwell needswork:owner] → [stockwell needswork:owner]

Checked the last 20 failure logs and most of the failures occur under dom/serviceworkers/test/test_xslt.html and then there are some under dom/cache/test/mochitest/test_chrome_constructor.html

Assignee: nobody → apavel
Status: NEW → ASSIGNED
Assignee: apavel → nobody
Status: ASSIGNED → NEW
Keywords: leave-open
Attachment #9279359 - Attachment is obsolete: true

This is now happening only on ESR 102, so our worker refactorings seemed to help here, too.

Flags: needinfo?(jstutte)
No longer blocks: LSan

Bulk closing intermittent bugs for DOM LWS that have not seen new instances since at least 5 months.

Status: NEW → RESOLVED
Closed: 7 months ago
Resolution: --- → INCOMPLETE

Taking back the bulk change.

Status: RESOLVED → UNCONFIRMED
Ever confirmed: false
Resolution: INCOMPLETE → ---
Status: UNCONFIRMED → NEW
Ever confirmed: true
Keywords: leave-open
Status: NEW → RESOLVED
Closed: 7 months ago2 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: