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

Reporter

Description

•

3 years ago

treeherder

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

Iulian Moraru

Updated

•

3 years ago

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

Andrew McCreight [:mccr8]

Comment 1

•

3 years ago

This looks like a leak related to a RemoteWorkerChild runnable.

Component: General → DOM: Workers

Noemi Erli[:noemi_erli]

Updated

•

3 years ago

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

Comment hidden (Intermittent Failures Robot)

Jens Stutte [:jstutte]

Updated

•

3 years ago

Flags: needinfo?(jstutte)

Comment hidden (Intermittent Failures Robot)

Takanori MATSUURA

Updated

•

3 years ago

Blocks: LSan

Comment hidden (Intermittent Failures Robot)

Jens Stutte [:jstutte]

Updated

•

3 years ago

See Also: → https://bugzilla.mozilla.org/show_bug.cgi?id=1749068

Comment hidden (Intermittent Failures Robot)

Atila Butkovits

Comment 19

•

2 years ago

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

Comment hidden (Intermittent Failures Robot)

Marian-Vasile Laza

Comment 21

•

2 years ago

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

linux1804-64-asan-qr

Comment hidden (Intermittent Failures Robot)

Marian-Vasile Laza

Comment 23

•

2 years ago

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

linux1804-64-asan-qr

Comment hidden (Intermittent Failures Robot)

Andreea Pavel [:apavel]

Updated

•

2 years ago

Whiteboard: [stockwell unknown][stockwell needswork:owner] → [stockwell needswork:owner]

Andreea Pavel [:apavel]

Comment 25

•

2 years ago

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

Andreea Pavel [:apavel]

Comment 26

•

2 years ago

Attached file Bug 1750062 - disable tests on linux64 opt to reduce failures r?#intermittent-reviewers (obsolete) (deleted) — Details

Phabricator Automation

Updated

•

2 years ago

Assignee: nobody → apavel

Status: NEW → ASSIGNED

Andreea Pavel [:apavel]

Updated

•

2 years ago

Assignee: apavel → nobody

Status: ASSIGNED → NEW

Keywords: leave-open

Comment hidden (Intermittent Failures Robot)

Phabricator Automation

Updated

•

2 years ago

Attachment #9279359 - Attachment is obsolete: true

Comment hidden (Intermittent Failures Robot)

Jens Stutte [:jstutte]

Comment 61

•

1 year ago

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