1744970 - Intermittent leakcheck | tab 10248 bytes leaked (CondVar, Mutex, ThreadEventTarget, ThreadTargetSink, nsAuthURLParser, ...)

Status: RESOLVED INCOMPLETE

(Core :: DOM: HTML Parser, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-12-08T13:48:45.475Z] 13:48:45     INFO - TEST-START | /encoding/legacy-mb-japanese/shift_jis/sjis-encode-form-errors-hangul.html?9001-10000
[task 2021-12-08T13:48:45.476Z] 13:48:45     INFO - Closing window ef483c98-d103-4f9f-a618-6819f9d438a4
[task 2021-12-08T13:48:45.477Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.478Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.478Z] 13:48:45     INFO - PID 1664 | [Child 1870, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:820
[task 2021-12-08T13:48:45.483Z] 13:48:45     INFO - PID 1664 | [Child 1870, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.512Z] 13:48:45     INFO - PID 1664 | [Child 1872, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/antitracking/URLQueryStringStripper.cpp:129
[task 2021-12-08T13:48:45.552Z] 13:48:45     INFO - PID 1839 | [RDD 1855, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:820
[task 2021-12-08T13:48:45.553Z] 13:48:45     INFO - PID 1839 | [Parent 1839, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:820
[task 2021-12-08T13:48:45.553Z] 13:48:45     INFO - PID 1839 | [RDD 1855, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4439
[task 2021-12-08T13:48:45.558Z] 13:48:45     INFO - PID 1839 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-12-08T13:48:45.572Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.738Z] 13:48:45     INFO - PID 1664 | [Child 1870, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.739Z] 13:48:45     INFO - PID 1664 | [Child 1870, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.780Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] ###!!! ASSERTION: Failed Dispatch after xpcom-shutdown-threads: 'false', file /builds/worker/checkouts/gecko/xpcom/threads/ThreadEventTarget.cpp:59
[task 2021-12-08T13:48:45.781Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: Failed to dispatch TimerKungFu event: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:2608
[task 2021-12-08T13:48:45.782Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: Failed to dispatch releaser event.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamListener.cpp:39
[task 2021-12-08T13:48:45.782Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] ###!!! ASSERTION: Failed Dispatch after xpcom-shutdown-threads: 'false', file /builds/worker/checkouts/gecko/xpcom/threads/ThreadEventTarget.cpp:59
[task 2021-12-08T13:48:45.783Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: Failed to dispatch TimerKungFu event: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:2608
[task 2021-12-08T13:48:45.784Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.812Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.820Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3351
[task 2021-12-08T13:48:45.822Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.825Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.826Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.827Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-08T13:48:45.831Z] 13:48:45     INFO - PID 1839 | [Child 1871, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4439
[task 2021-12-08T13:48:45.832Z] 13:48:45     INFO - PID 1839 | Leaked URLs:
[task 2021-12-08T13:48:45.833Z] 13:48:45     INFO - PID 1839 |   http://web-platform.test:8000/html/infrastructure/urls/resolving-urls/query-encoding/resources/stash.py?q=%E5&action=put&id=ffee4978-d782-4a8a-b778-dab5ffa82719
[task 2021-12-08T13:48:45.833Z] 13:48:45     INFO - PID 1839 | nsStringStats
<...>
[task 2021-12-08T13:48:46.394Z] 13:48:46     INFO - nsTraceRefcnt::DumpStatistics: 1196 entries
[task 2021-12-08T13:48:46.394Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 1 CondVar
[task 2021-12-08T13:48:46.394Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 14 Mutex
[task 2021-12-08T13:48:46.395Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 1 ThreadEventTarget
[task 2021-12-08T13:48:46.395Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 1 ThreadTargetSink
[task 2021-12-08T13:48:46.395Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 1 nsAuthURLParser
[task 2021-12-08T13:48:46.396Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsContentSink
[task 2021-12-08T13:48:46.396Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5AttributeName
[task 2021-12-08T13:48:46.397Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5DocumentBuilder
[task 2021-12-08T13:48:46.397Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5ElementName
[task 2021-12-08T13:48:46.397Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5LoadFlusher
[task 2021-12-08T13:48:46.398Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5StreamParser
[task 2021-12-08T13:48:46.398Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 4 nsHtml5TimerKungFu
[task 2021-12-08T13:48:46.398Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5Tokenizer
[task 2021-12-08T13:48:46.399Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5TreeBuilder
[task 2021-12-08T13:48:46.399Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsHtml5TreeOpExecutor
[task 2021-12-08T13:48:46.400Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsStandardURL
[task 2021-12-08T13:48:46.400Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 3 nsStringBuffer
[task 2021-12-08T13:48:46.400Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 1 nsThread
[task 2021-12-08T13:48:46.401Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsTimer
[task 2021-12-08T13:48:46.401Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 2 nsTimerImpl
[task 2021-12-08T13:48:46.402Z] 13:48:46     INFO - TEST-UNEXPECTED-FAIL | leakcheck | tab 10248 bytes leaked (CondVar, Mutex, ThreadEventTarget, ThreadTargetSink, nsAuthURLParser, ...)
[task 2021-12-08T13:48:46.402Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.403Z] 13:48:46     INFO - leakcheck | Processing leak log file /var/folders/q3/8_7hmztn151_npn33m435294000014/T/tmp4ggifo7l/runtests_leaks_1305_tab_pid1848.log
[task 2021-12-08T13:48:46.403Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.403Z] 13:48:46     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1848
[task 2021-12-08T13:48:46.404Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.404Z] 13:48:46     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-12-08T13:48:46.404Z] 13:48:46     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-12-08T13:48:46.405Z] 13:48:46     INFO -    0 |TOTAL                                 |       64        0|  380160        0|
[task 2021-12-08T13:48:46.405Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.406Z] 13:48:46     INFO - nsTraceRefcnt::DumpStatistics: 1112 entries
[task 2021-12-08T13:48:46.406Z] 13:48:46     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-12-08T13:48:46.406Z] 13:48:46     INFO - leakcheck | Processing leak log file /var/folders/q3/8_7hmztn151_npn33m435294000014/T/tmp4ggifo7l/runtests_leaks_1305_tab_pid1849.log
[task 2021-12-08T13:48:46.407Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.407Z] 13:48:46     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1849
[task 2021-12-08T13:48:46.407Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.408Z] 13:48:46     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-12-08T13:48:46.408Z] 13:48:46     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-12-08T13:48:46.409Z] 13:48:46     INFO -    0 |TOTAL                                 |       65     5544|  277228       29|
[task 2021-12-08T13:48:46.409Z] 13:48:46     INFO -   77 |CondVar                               |       80       80|      79        1|
[task 2021-12-08T13:48:46.410Z] 13:48:46     INFO -  289 |Mutex                                 |       96      768|    1944        8|
[task 2021-12-08T13:48:46.410Z] 13:48:46     INFO -  583 |ThreadEventTarget                     |       64       64|      14        1|
[task 2021-12-08T13:48:46.410Z] 13:48:46     INFO -  588 |ThreadTargetSink                      |       16       16|      14        1|
[task 2021-12-08T13:48:46.411Z] 13:48:46     INFO -  730 |nsAuthURLParser                       |       24       24|       2        1|
[task 2021-12-08T13:48:46.411Z] 13:48:46     INFO -  762 |nsContentSink                         |      192      192|      34        1|
[task 2021-12-08T13:48:46.412Z] 13:48:46     INFO -  837 |nsHtml5AttributeName                  |       32       32|     536        1|
[task 2021-12-08T13:48:46.412Z] 13:48:46     INFO -  839 |nsHtml5DocumentBuilder                |      472      472|      19        1|
[task 2021-12-08T13:48:46.412Z] 13:48:46     INFO -  840 |nsHtml5ElementName                    |       40       40|     245        1|
[task 2021-12-08T13:48:46.413Z] 13:48:46     INFO -  844 |nsHtml5LoadFlusher                    |       40       40|      36        1|
[task 2021-12-08T13:48:46.413Z] 13:48:46     INFO -  855 |nsHtml5StreamParser                   |      992      992|      18        1|
[task 2021-12-08T13:48:46.414Z] 13:48:46     INFO -  858 |nsHtml5TimerKungFu                    |       40       80|      19        2|
[task 2021-12-08T13:48:46.414Z] 13:48:46     INFO -  859 |nsHtml5Tokenizer                      |      304      304|      37        1|
[task 2021-12-08T13:48:46.414Z] 13:48:46     INFO -  860 |nsHtml5TreeBuilder                    |      624      624|      37        1|
[task 2021-12-08T13:48:46.415Z] 13:48:46     INFO -  861 |nsHtml5TreeOpExecutor                 |      720      720|      19        1|
[task 2021-12-08T13:48:46.415Z] 13:48:46     INFO -  960 |nsStandardURL                         |      336      336|    5771        1|
[task 2021-12-08T13:48:46.416Z] 13:48:46     INFO -  967 |nsStringBuffer                        |        8       16|   42293        2|
[task 2021-12-08T13:48:46.416Z] 13:48:46     INFO - 1003 |nsThread                              |      456      456|      23        1|
[task 2021-12-08T13:48:46.417Z] 13:48:46     INFO - 1009 |nsTimer                               |       32       32|     435        1|
[task 2021-12-08T13:48:46.417Z] 13:48:46     INFO - 1011 |nsTimerImpl                           |      256      256|     435        1|
[task 2021-12-08T13:48:46.417Z] 13:48:46     INFO - 
[task 2021-12-08T13:48:46.418Z] 13:48:46     INFO - nsTraceRefcnt::DumpStatistics: 1046 entries
[task 2021-12-08T13:48:46.418Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 1 CondVar
[task 2021-12-08T13:48:46.418Z] 13:48:46     INFO - TEST-INFO | leakcheck | tab leaked 8 Mutex

Comment 1

[Intermittent Failures Robot]

19 failures in 3319 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-central: 2

Platform and build breakdown:

• macosx1015-64-qr: 19
  • debug: 19

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1744970&startday=2021-12-06&endday=2021-12-12&tree=all

Comment 2

[Andrew McCreight [:mccr8]]

This looks like an HTML parser leak happening during OSX runs of WPTs in the /encoding/legacy-mb-japanese/shift_jis/ directory.

Comment 3

[Intermittent Failures Robot]

24 failures in 3263 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 15
• mozilla-central: 9

Platform and build breakdown:

• macosx1015-64-qr: 24
  • debug: 24

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1744970&startday=2021-12-13&endday=2021-12-19&tree=all

Comment 4

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

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