Closed Bug 1672510 Opened 4 years ago Closed 4 years ago

Intermittent leakcheck | rdd 3704 bytes leaked (AbstractThread, ActorLifecycleProxy, CondVar, IPC::Channel, Mutex, ...)

Categories

(Core :: Layout: Text and Fonts, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Assigned: jya)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 obsolete file)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=319290681&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MTmqzLUPT2C3JUHuqf3kXA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MTmqzLUPT2C3JUHuqf3kXA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2020-10-21T19:37:52.545Z] 19:37:52 INFO - TEST-FAIL | /css/css-text/hanging-punctuation/hanging-punctuation-inline-001.html | took 1246ms
[task 2020-10-21T19:37:52.546Z] 19:37:52 INFO - TEST-START | /css/css-text/hanging-punctuation/hanging-scrollable-001.html
[task 2020-10-21T19:37:52.565Z] 19:37:52 INFO - PID 11044 | 1603309072547 Marionette INFO Testing http://web-platform.test:8000/css/css-text/hanging-punctuation/hanging-scrollable-001.html == http://web-platform.test:8000/css/css-text/hanging-punctuation/reference/hanging-scrollable-001-ref.html
[task 2020-10-21T19:37:52.608Z] 19:37:52 INFO - PID 10364 | [Parent 1076, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-21T19:37:52.676Z] 19:37:52 INFO - Browser exited with return code 0
[task 2020-10-21T19:37:52.677Z] 19:37:52 INFO - PROCESS LEAKS c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020.log
[task 2020-10-21T19:37:52.677Z] 19:37:52 INFO - leakcheck | Processing log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020.log for scope /css/compositing/mix-blend-mode
[task 2020-10-21T19:37:52.678Z] 19:37:52 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 51200 bytes
[task 2020-10-21T19:37:52.678Z] 19:37:52 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-10-21T19:37:52.679Z] 19:37:52 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-10-21T19:37:52.679Z] 19:37:52 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 51200 bytes
[task 2020-10-21T19:37:52.680Z] 19:37:52 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-10-21T19:37:52.680Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2020-10-21T19:37:52.681Z] 19:37:52 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-10-21T19:37:52.681Z] 19:37:52 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2020-10-21T19:37:52.681Z] 19:37:52 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2020-10-21T19:37:52.682Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020.log
[task 2020-10-21T19:37:52.684Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.684Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 1076
[task 2020-10-21T19:37:52.685Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.685Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.685Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.686Z] 19:37:52 INFO - 0 |TOTAL | 41 0| 1216334 0|
[task 2020-10-21T19:37:52.707Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.707Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 1916 entries
[task 2020-10-21T19:37:52.708Z] 19:37:52 INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2020-10-21T19:37:52.709Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_gpu_pid1376.log
[task 2020-10-21T19:37:52.709Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.709Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, gpu process 1376
[task 2020-10-21T19:37:52.710Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.710Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.710Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.710Z] 19:37:52 INFO - 0 |TOTAL | 39 0| 84929 0|
[task 2020-10-21T19:37:52.712Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.713Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 382 entries
[task 2020-10-21T19:37:52.714Z] 19:37:52 INFO - TEST-PASS | leakcheck | gpu no leaks detected!
[task 2020-10-21T19:37:52.715Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_rdd_pid6248.log
[task 2020-10-21T19:37:52.716Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.716Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, rdd process 6248
[task 2020-10-21T19:37:52.716Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.717Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.718Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.719Z] 19:37:52 INFO - 0 |TOTAL | 30 3704| 4974 22|
[task 2020-10-21T19:37:52.720Z] 19:37:52 INFO - 1 |AbstractThread | 40 40| 2 1|
[task 2020-10-21T19:37:52.721Z] 19:37:52 INFO - 2 |ActorLifecycleProxy | 32 32| 5 1|
[task 2020-10-21T19:37:52.721Z] 19:37:52 INFO - 15 |CondVar | 64 128| 22 2|
[task 2020-10-21T19:37:52.722Z] 19:37:52 INFO - 27 |IPC::Channel | 8 8| 5 1|
[task 2020-10-21T19:37:52.723Z] 19:37:52 INFO - 45 |Mutex | 80 240| 199 3|
[task 2020-10-21T19:37:52.723Z] 19:37:52 INFO - 46 |PDMFactory | 56 56| 2 1|
[task 2020-10-21T19:37:52.724Z] 19:37:52 INFO - 57 |PRemoteDecoderManagerParent | 688 688| 2 1|
[task 2020-10-21T19:37:52.725Z] 19:37:52 INFO - 61 |PlatformDecoderModule | 16 64| 8 4|
[task 2020-10-21T19:37:52.726Z] 19:37:52 INFO - 69 |RefCountedMonitor | 152 152| 5 1|
[task 2020-10-21T19:37:52.727Z] 19:37:52 INFO - 71 |RemoteDecoderManagerParent | 752 1504| 4 2|
[task 2020-10-21T19:37:52.728Z] 19:37:52 INFO - 81 |StoreRef | 16 16| 5 1|
[task 2020-10-21T19:37:52.728Z] 19:37:52 INFO - 87 |TaskQueue | 336 336| 1 1|
[task 2020-10-21T19:37:52.729Z] 19:37:52 INFO - 113 |ipc::MessageChannel | 392 392| 5 1|
[task 2020-10-21T19:37:52.729Z] 19:37:52 INFO - 114 |ipc::MessageChannel::DispatchOnChannel| 40 40| 5 1|
[task 2020-10-21T19:37:52.729Z] 19:37:52 INFO - 128 |nsTArray_base | 8 8| 819 1|
[task 2020-10-21T19:37:52.730Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.730Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 137 entries
[task 2020-10-21T19:37:52.731Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 AbstractThread
[task 2020-10-21T19:37:52.731Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 ActorLifecycleProxy
[task 2020-10-21T19:37:52.731Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 2 CondVar
[task 2020-10-21T19:37:52.732Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 IPC::Channel
[task 2020-10-21T19:37:52.732Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 3 Mutex
[task 2020-10-21T19:37:52.732Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 PDMFactory
[task 2020-10-21T19:37:52.733Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 PRemoteDecoderManagerParent
[task 2020-10-21T19:37:52.733Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 4 PlatformDecoderModule
[task 2020-10-21T19:37:52.733Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 RefCountedMonitor
[task 2020-10-21T19:37:52.733Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 2 RemoteDecoderManagerParent
[task 2020-10-21T19:37:52.734Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 StoreRef
[task 2020-10-21T19:37:52.734Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 TaskQueue
[task 2020-10-21T19:37:52.734Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 ipc::MessageChannel
[task 2020-10-21T19:37:52.735Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 ipc::MessageChannel::DispatchOnChannel
[task 2020-10-21T19:37:52.735Z] 19:37:52 INFO - TEST-INFO | leakcheck | rdd leaked 1 nsTArray_base
[task 2020-10-21T19:37:52.735Z] 19:37:52 INFO - TEST-UNEXPECTED-FAIL | leakcheck | rdd 3704 bytes leaked (AbstractThread, ActorLifecycleProxy, CondVar, IPC::Channel, Mutex, ...)
[task 2020-10-21T19:37:52.736Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.736Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_tab_pid1760.log
[task 2020-10-21T19:37:52.736Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.736Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1760
[task 2020-10-21T19:37:52.737Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.738Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.739Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.740Z] 19:37:52 INFO - 0 |TOTAL | 39 0| 969177 0|
[task 2020-10-21T19:37:52.740Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.740Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 1199 entries
[task 2020-10-21T19:37:52.741Z] 19:37:52 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-10-21T19:37:52.741Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_tab_pid2344.log
[task 2020-10-21T19:37:52.742Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.742Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 2344
[task 2020-10-21T19:37:52.743Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.743Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.744Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.744Z] 19:37:52 INFO - 0 |TOTAL | 41 0| 34233 0|
[task 2020-10-21T19:37:52.754Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.754Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 962 entries
[task 2020-10-21T19:37:52.755Z] 19:37:52 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-10-21T19:37:52.755Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_tab_pid384.log
[task 2020-10-21T19:37:52.756Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.757Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 384
[task 2020-10-21T19:37:52.757Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.758Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.758Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.758Z] 19:37:52 INFO - 0 |TOTAL | 37 0| 14198 0|
[task 2020-10-21T19:37:52.758Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.759Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 402 entries
[task 2020-10-21T19:37:52.759Z] 19:37:52 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-10-21T19:37:52.759Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_tab_pid6356.log
[task 2020-10-21T19:37:52.760Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.760Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 6356
[task 2020-10-21T19:37:52.760Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.761Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.761Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.761Z] 19:37:52 INFO - 0 |TOTAL | 41 0| 101771 0|
[task 2020-10-21T19:37:52.771Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.771Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 852 entries
[task 2020-10-21T19:37:52.772Z] 19:37:52 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-10-21T19:37:52.772Z] 19:37:52 INFO - leakcheck | Processing leak log file c:\users\task_1603307930\appdata\local\temp\tmp_186p1\runtests_leaks_11020_tab_pid7468.log
[task 2020-10-21T19:37:52.772Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.772Z] 19:37:52 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 7468
[task 2020-10-21T19:37:52.773Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.773Z] 19:37:52 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-10-21T19:37:52.773Z] 19:37:52 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-10-21T19:37:52.774Z] 19:37:52 INFO - 0 |TOTAL | 40 0| 42198 0|
[task 2020-10-21T19:37:52.779Z] 19:37:52 INFO -
[task 2020-10-21T19:37:52.779Z] 19:37:52 INFO - nsTraceRefcnt::DumpStatistics: 773 entries
[task 2020-10-21T19:37:52.779Z] 19:37:52 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-10-21T19:37:52.781Z] 19:37:52 INFO - Closing logging queue
[task 2020-10-21T19:37:52.781Z] 19:37:52 INFO - queue closed
[task 2020-10-21T19:37:52.850Z] 19:37:52 INFO - PID 11044 | 1603309072843 Marionette INFO No differences allowed
[task 2020-10-21T19:37:52.888Z] 19:37:52 INFO - TEST-FAIL | /css/css-text/hanging-punctuation/hanging-scrollable-001.html | took 347ms
[task 2020-10-21T19:37:52.898Z] 19:37:52 INFO - Restarting browser for new test group
[task 2020-10-21T19:37:52.960Z] 19:37:52 INFO - PID 11044 | [GPU 9772, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2020-10-21T19:37:52.981Z] 19:37:52 INFO - Application command: Z:\task_1603307930\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile c:\users\task_1603307930\appdata\local\temp\tmplozcge
[task 2020-10-21T19:37:53.070Z] 19:37:53 INFO - Starting runner
[task 2020-10-21T19:37:53.079Z] 19:37:53 INFO - PID 11044 | 1603309073065 Marionette INFO Stopped listening on port 54802
[task 2020-10-21T19:37:53.237Z] 19:37:53 INFO - PID 680 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1603307930\appdata\local\temp\tmplozcge\runtests_leaks_11020.log
[task 2020-10-21T19:37:53.238Z] 19:37:53 INFO - PID 680 | [9548, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:249
[task 2020-10-21T19:37:53.238Z] 19:37:53 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:41
[task 2020-10-21T19:37:53.238Z] 19:37:53 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:41
[task 2020-10-21T19:37:53.260Z] 19:37:53 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:41
[task 2020-10-21T19:37:53.286Z] 19:37:53 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:41
[task 2020-10-21T19:37:53.287Z] 19:37:53 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:41
[task 2020-10-21T19:37:53.522Z] 19:37:53 INFO - PID 11044 | [GPU 9772, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2020-10-21T19:37:53.522Z] 19:37:53 INFO - PID 11044 | [GPU 9772, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2020-10-21T19:37:53.697Z] 19:37:53 INFO - PID 11044 | [2020-10-21T19:37:53Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-10-21T19:37:53.697Z] 19:37:53 INFO - PID 11044 | [2020-10-21T19:37:53Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-10-21T19:37:54.167Z] 19:37:54 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-10-21T19:37:54.186Z] 19:37:54 INFO - PID 11044 | [Child 8264, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-21T19:37:55.205Z] 19:37:55 INFO - PID 680 | [2020-10-21T19:37:55Z WARN rkv::backend::impl_safe::environment] load_ratio() is irrelevant for this storage backend.
[task 2020-10-21T19:37:55.603Z] 19:37:55 INFO - PID 11044 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-10-21T19:37:55.664Z] 19:37:55 INFO - PID 11044 | [Child 2908, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-10-21T19:37:55.713Z] 19:37:55 INFO - PID 11044 | [Child 2908, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-21T19:37:55.735Z] 19:37:55 INFO - PID 11044 | [Child 11052, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-10-21T19:37:55.769Z] 19:37:55 INFO - PID 11044 | [Child 11052, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-21T19:37:55.814Z] 19:37:55 INFO - PID 680 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1603307930\appdata\local\temp\tmplozcge\runtests_leaks_11020_gpu_pid10872.log
[task 2020-10-21T19:37:55.814Z] 19:37:55 INFO - PID 680 | [10872, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:249
[task 2020-10-21T19:37:55.890Z] 19:37:55 INFO - PID 11044 | [Child 8600, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2020-10-21T19:37:55.923Z] 19:37:55 INFO - PID 11044 | [Child 8600, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-21T19:37:56.102Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-21T19:37:56.103Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: Error sending reply: file /builds/worker/workspace/obj-build/ipc/ipdl/PContentParent.cpp:12602
[task 2020-10-21T19:37:56.103Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-21T19:37:56.104Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: Error sending reply: file /builds/worker/workspace/obj-build/ipc/ipdl/PContentParent.cpp:12602
[task 2020-10-21T19:37:56.106Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-21T19:37:56.107Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: Error sending reply: file /builds/worker/workspace/obj-build/ipc/ipdl/PContentParent.cpp:12602
[task 2020-10-21T19:37:56.107Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-21T19:37:56.109Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: Error sending reply: file /builds/worker/workspace/obj-build/ipc/ipdl/PContentParent.cpp:12602
[task 2020-10-21T19:37:56.109Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-21T19:37:56.112Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: Error sending reply: file /builds/worker/workspace/obj-build/ipc/ipdl/PContentParent.cpp:12602
[task 2020-10-21T19:37:56.112Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-21T19:37:56.114Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: Error sending reply: file /builds/worker/workspace/obj-build/ipc/ipdl/PContentParent.cpp:12602
[task 2020-10-21T19:37:56.350Z] 19:37:56 INFO - PID 680 | [9548, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4284
[task 2020-10-21T19:37:56.365Z] 19:37:56 INFO - PID 680 | [9548, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4284
[task 2020-10-21T19:37:56.412Z] 19:37:56 INFO - PID 11044 | [Parent 5916, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp:1276
[task 2020-10-21T19:37:56.556Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glBlendBarrierKHR" by glcontext
[task 2020-10-21T19:37:56.557Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glCopyImageSubDataEXT" by glcontext
[task 2020-10-21T19:37:56.557Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glDebugMessageCallback" by glcontext
[task 2020-10-21T19:37:56.558Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glDebugMessageCallbackARB" by glcontext
[task 2020-10-21T19:37:56.558Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glDebugMessageControl" by glcontext
[task 2020-10-21T19:37:56.559Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glDebugMessageControlARB" by glcontext
[task 2020-10-21T19:37:56.559Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glDebugMessageInsert" by glcontext
[task 2020-10-21T19:37:56.561Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glDebugMessageInsertARB" by glcontext
[task 2020-10-21T19:37:56.562Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glGetDebugMessageLog" by glcontext
[task 2020-10-21T19:37:56.563Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glGetDebugMessageLogARB" by glcontext
[task 2020-10-21T19:37:56.564Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glGetObjectLabel" by glcontext
[task 2020-10-21T19:37:56.564Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glGetObjectPtrLabel" by glcontext
[task 2020-10-21T19:37:56.565Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glObjectLabel" by glcontext
[task 2020-10-21T19:37:56.565Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glObjectPtrLabel" by glcontext
[task 2020-10-21T19:37:56.567Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glPopDebugGroup" by glcontext
[task 2020-10-21T19:37:56.567Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glPushDebugGroup" by glcontext
[task 2020-10-21T19:37:56.568Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glTextureStorage1DEXT" by glcontext
[task 2020-10-21T19:37:56.569Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glTextureStorage2DEXT" by glcontext
[task 2020-10-21T19:37:56.570Z] 19:37:56 INFO - PID 11044 | [2020-10-21T19:37:56Z WARN webrender_bindings::bindings] Could not find symbol "glTextureStorage3DEXT" by glcontext
[task 2020-10-21T19:37:56.570Z] 19:37:56 INFO - PID 11044 | Destroying context 000002578066F000 surface 00000257F43F2160 on display 0000025780639800
[task 2020-10-21T19:37:56.602Z] 19:37:56 INFO - PID 11044 | [GPU 9772, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287

This looks awfully similiar to bug 1653060; Jean-Yves, any thoughts on this?

Flags: needinfo?(jyavenard)

(In reply to Jonathan Kew (:jfkthame) from comment #1)

This looks awfully similiar to bug 1653060; Jean-Yves, any thoughts on this?

It does.

Previously the RDD was only used for AV1 and some audio codecs, now it's used for everything which increase the coverage greatly.

So another RemoteDecoderManagerParent shutdown leak.

In the log, it seems that the parent process got shutdown before the content process. When the parent process gets killed, it shutdown the RDD process first. So the remaining content process would have no way to signal the RDD that they are shutting down.

I added a wait in the RDD shutdown procedure, but obviously not enough.

Flags: needinfo?(jyavenard)
See Also: → 1674026

A full description of the problem is found at https://bugzilla.mozilla.org/show_bug.cgi?id=1653060#c15

In bug 1681043 we launch the RDD process just after the first content process.

Should the parent process shut down at that time, we have a race happening between the PRemoteDecoderManager message between content and RDD and the shutdown happening on the PRDD between parent and RDD.

If the RemoteDecoderManagerParent didn't yet have the time to register itself to RDDParent::mShutdownBlockers ; the process will be immediately killed and a leak would be reported.

Those aren't real leaks ; it's a shutdown leak, only possible in Nightly and in debug configuration.
So raising the threshold is the easiest thing to do.

Assignee: nobody → jya-moz
Status: NEW → ASSIGNED
Attachment #9194362 - Attachment description: Bug 1672510 - Increase RDD leak threshold to 4000 for now. r=mattwoodrow!,alwu! → Bug 1672510 - Increase RDD leak threshold to 7000 for now. r=mattwoodrow!,alwu!
Attachment #9194362 - Attachment is obsolete: true
Status: ASSIGNED → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: