Closed Bug 1441137 Opened 6 years ago Closed 4 years ago

Intermittent Assertion failure: rc != 0 (destroyed timer off its target thread!), at /builds/worker/workspace/build/src/xpcom/threads/TimerThread.cpp:476

Categories

(Core :: XPCOM, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE

I do not see any worker related things in the stacktrace. Could be an underlying issue in XPCOM code?

Flags: needinfo?(nfroyd)

(In reply to Jens Stutte [:jstutte] from comment #91)

I do not see any worker related things in the stacktrace. Could be an underlying issue in XPCOM code?

Well, it's some kind of issue with whoever is using the timer, not the xpcom code specifically. The failures all appear to be in devtools-chrome tests, which could be using workers? If you really wanted to move it to Core::XPCOM, that'd be fine.

Flags: needinfo?(nfroyd)
Component: DOM: Workers → XPCOM

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=290606132&repo=mozilla-central&lineNumber=2207

[task 2020-02-26T17:11:58.457Z] 17:11:58     INFO - REFTEST TEST-START | layout/reftests/box-sizing/html-hr-1.html == layout/reftests/box-sizing/html-hr-1-ref.html
[task 2020-02-26T17:11:58.457Z] 17:11:58     INFO - REFTEST TEST-LOAD | file:///Z:/task_1582735791/build/tests/reftest/tests/layout/reftests/box-sizing/html-hr-1.html | 16 / 17 (94%)
[task 2020-02-26T17:11:58.496Z] 17:11:58     INFO - [Child 4180, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-26T17:11:58.752Z] 17:11:58     INFO - REFTEST TEST-LOAD | file:///Z:/task_1582735791/build/tests/reftest/tests/layout/reftests/box-sizing/html-hr-1-ref.html | 16 / 17 (94%)
[task 2020-02-26T17:11:58.815Z] 17:11:58     INFO - [Child 4180, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-26T17:11:59.066Z] 17:11:59     INFO - REFTEST TEST-PASS | layout/reftests/box-sizing/html-hr-1.html == layout/reftests/box-sizing/html-hr-1-ref.html | image comparison, max difference: 0, number of differing pixels: 0
[task 2020-02-26T17:11:59.066Z] 17:11:59     INFO - REFTEST TEST-END | layout/reftests/box-sizing/html-hr-1.html == layout/reftests/box-sizing/html-hr-1-ref.html
[task 2020-02-26T17:11:59.097Z] 17:11:59     INFO - [Child 4180, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-26T17:11:59.127Z] 17:11:59     INFO - REFTEST INFO | Slowest test took 296ms (file:///Z:/task_1582735791/build/tests/reftest/tests/layout/reftests/box-sizing/html-hr-1-ref.html)
[task 2020-02-26T17:11:59.127Z] 17:11:59     INFO - REFTEST INFO | Total canvas count = 2
[task 2020-02-26T17:11:59.940Z] 17:11:59     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-26T17:11:59.967Z] 17:11:59     INFO - [Parent 4868, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 279
[task 2020-02-26T17:11:59.968Z] 17:11:59     INFO - Assertion failure: rc != 0 (destroyed timer off its target thread!), at /builds/worker/workspace/build/src/xpcom/threads/TimerThread.cpp:443
[task 2020-02-26T17:12:00.096Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 479
[task 2020-02-26T17:12:00.097Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: '!ipcActor->SendAccumulateChildHistograms(histogramsToSend)', file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 282
[task 2020-02-26T17:12:00.097Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 479
[task 2020-02-26T17:12:00.097Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 286
[task 2020-02-26T17:12:00.098Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 479
[task 2020-02-26T17:12:00.103Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 301
[task 2020-02-26T17:12:00.185Z] 17:12:00     INFO - #01: Ordinal0[Z:\task_1582735791\build\application\firefox\xul.dll +0x19e0a2]
[task 2020-02-26T17:12:00.185Z] 17:12:00     INFO - #02: Ordinal0[Z:\task_1582735791\build\application\firefox\xul.dll +0x97acc5]
[task 2020-02-26T17:12:00.185Z] 17:12:00     INFO - #03: Ordinal0[Z:\task_1582735791\build\application\firefox\xul.dll +0x9168e8]
[task 2020-02-26T17:12:00.185Z] 17:12:00     INFO - #04: Ordinal0[Z:\task_1582735791\build\application\firefox\xul.dll +0x1948dd]
[task 2020-02-26T17:12:00.186Z] 17:12:00     INFO - #05: PRP_TryLock[Z:\task_1582735791\build\application\firefox\nss3.dll +0x167515]
[task 2020-02-26T17:12:00.186Z] 17:12:00     INFO - #06: PR_MD_UNLOCK[Z:\task_1582735791\build\application\firefox\nss3.dll +0x156ed0]
[task 2020-02-26T17:12:00.186Z] 17:12:00     INFO - #07: o_ceil[C:\Windows\System32\ucrtbase.dll +0x1c4be]
[task 2020-02-26T17:12:00.186Z] 17:12:00     INFO - #08: BaseThreadInitThunk[C:\Windows\System32\KERNEL32.DLL +0x13034]
[task 2020-02-26T17:12:00.187Z] 17:12:00     INFO - #09: RtlUserThreadStart[C:\Windows\SYSTEM32\ntdll.dll +0x71461]
[task 2020-02-26T17:12:00.387Z] 17:12:00     INFO - [Child 832, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-02-26T17:12:00.427Z] 17:12:00     INFO - nsStringStats
[task 2020-02-26T17:12:00.428Z] 17:12:00     INFO -  => mAllocCount:          23817
[task 2020-02-26T17:12:00.428Z] 17:12:00     INFO -  => mReallocCount:            0
[task 2020-02-26T17:12:00.429Z] 17:12:00     INFO -  => mFreeCount:           23817
[task 2020-02-26T17:12:00.429Z] 17:12:00     INFO -  => mShareCount:          57727
[task 2020-02-26T17:12:00.429Z] 17:12:00     INFO -  => mAdoptCount:            436
[task 2020-02-26T17:12:00.429Z] 17:12:00     INFO -  => mAdoptFreeCount:        472
[task 2020-02-26T17:12:00.430Z] 17:12:00     INFO -  => Process ID: 832, Thread ID: 4980
[task 2020-02-26T17:12:00.456Z] 17:12:00     INFO - [Child 4180, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-02-26T17:12:00.496Z] 17:12:00     INFO - nsStringStats
[task 2020-02-26T17:12:00.496Z] 17:12:00     INFO -  => mAllocCount:          12684
[task 2020-02-26T17:12:00.497Z] 17:12:00     INFO -  => mReallocCount:            0
[task 2020-02-26T17:12:00.497Z] 17:12:00     INFO -  => mFreeCount:           12684
[task 2020-02-26T17:12:00.497Z] 17:12:00     INFO -  => mShareCount:          16487
[task 2020-02-26T17:12:00.497Z] 17:12:00     INFO -  => mAdoptCount:            614
[task 2020-02-26T17:12:00.498Z] 17:12:00     INFO -  => mAdoptFreeCount:        694
[task 2020-02-26T17:12:00.498Z] 17:12:00     INFO -  => Process ID: 4180, Thread ID: 9860
[task 2020-02-26T17:12:00.629Z] 17:12:00     INFO - [GPU 10976, Main Thread] WARNING: Shutting down GPU process early due to a crash!: file /builds/worker/workspace/build/src/gfx/ipc/GPUParent.cpp, line 519
[task 2020-02-26T17:12:00.719Z] 17:12:00    ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 1
[task 2020-02-26T17:12:00.719Z] 17:12:00     INFO - REFTEST INFO | Copy/paste: Z:/task_1582735791/fetches\minidump_stackwalk\minidump_stackwalk.exe c:\users\task_1582735791\appdata\local\temp\tmpijnywv.mozrunner\minidumps\f3c2ba91-4819-4623-a299-d6a6cabe3e46.dmp Z:\task_1582735791\build\symbols
[task 2020-02-26T17:12:03.477Z] 17:12:03     INFO - REFTEST INFO | Saved minidump as Z:\task_1582735791\build\blobber_upload_dir\f3c2ba91-4819-4623-a299-d6a6cabe3e46.dmp
[task 2020-02-26T17:12:03.479Z] 17:12:03     INFO - REFTEST INFO | Saved app info as Z:\task_1582735791\build\blobber_upload_dir\f3c2ba91-4819-4623-a299-d6a6cabe3e46.extra
[task 2020-02-26T17:12:03.523Z] 17:12:03     INFO - REFTEST PROCESS-CRASH | Last test finished | application crashed [@ xul.dll + 0x18ef9b]
[task 2020-02-26T17:12:03.523Z] 17:12:03     INFO - Mozilla crash reason: MOZ_ASSERT(rc != 0) (destroyed timer off its target thread!)
[task 2020-02-26T17:12:03.523Z] 17:12:03     INFO - Crash dump filename: c:\users\task_1582735791\appdata\local\temp\tmpijnywv.mozrunner\minidumps\f3c2ba91-4819-4623-a299-d6a6cabe3e46.dmp
[task 2020-02-26T17:12:03.523Z] 17:12:03     INFO - Operating system: Windows NT
[task 2020-02-26T17:12:03.523Z] 17:12:03     INFO -                   10.0.17134 
[task 2020-02-26T17:12:03.524Z] 17:12:03     INFO - CPU: amd64
[task 2020-02-26T17:12:03.524Z] 17:12:03     INFO -      family 6 model 79 stepping 1
[task 2020-02-26T17:12:03.524Z] 17:12:03     INFO -      4 CPUs
[task 2020-02-26T17:12:03.524Z] 17:12:03     INFO - 
[task 2020-02-26T17:12:03.524Z] 17:12:03     INFO - GPU: UNKNOWN
[task 2020-02-26T17:12:03.524Z] 17:12:03     INFO - 
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO - Crash reason:  EXCEPTION_ACCESS_VIOLATION_WRITE
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO - Crash address: 0x0
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO - Process uptime: 14 seconds
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO - 
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO - Thread 9 (crashed)
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO -  0  xul.dll + 0x18ef9b
[task 2020-02-26T17:12:03.525Z] 17:12:03     INFO -     rax = 0x00007ffa823b85ea   rdx = 0x00007ffac4aea640
[task 2020-02-26T17:12:03.526Z] 17:12:03     INFO -     rcx = 0x00007ffaadd3a748   rbx = 0x0000023475b5f2c0
[task 2020-02-26T17:12:03.526Z] 17:12:03     INFO -     rsi = 0x00007ffaadd3a45c   rdi = 0x000002346cc7b720
[task 2020-02-26T17:12:03.526Z] 17:12:03     INFO -     rbp = 0x0000003a040bf320   rsp = 0x0000003a040bf2a0
[task 2020-02-26T17:12:03.526Z] 17:12:03     INFO -      r8 = 0x0000003a040b92b8    r9 = 0x0000003a040b9340
[task 2020-02-26T17:12:03.526Z] 17:12:03     INFO -     r10 = 0x0000000000000000   r11 = 0x0000003a040ba900
[task 2020-02-26T17:12:03.526Z] 17:12:03     INFO -     r12 = 0x0000003a040bf2e0   r13 = 0x000000002cd1ffc0
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -     r14 = 0x0000023461e4d200   r15 = 0x0000023461e4d280
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -     rip = 0x00007ffa7a32ef9b
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -     Found by: given as instruction pointer in context
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -  1  ucrtbase.dll!RtlpGetCompleteLanguageFallback + 0x6c
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -     rbp = 0x0000003a040bf320   rsp = 0x0000003a040bf2a8
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -     rip = 0x00007ffac4ae9510
[task 2020-02-26T17:12:03.527Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.528Z] 17:12:03     INFO -  2  0x19cec9acc360
[task 2020-02-26T17:12:03.528Z] 17:12:03     INFO -     rbp = 0x0000003a040bf320   rsp = 0x0000003a040bf308
[task 2020-02-26T17:12:03.528Z] 17:12:03     INFO -     rip = 0x000019cec9acc360
[task 2020-02-26T17:12:03.528Z] 17:12:03     INFO -     Found by: call frame info
[task 2020-02-26T17:12:03.528Z] 17:12:03     INFO -  3  xul.dll + 0xa3a9750
[task 2020-02-26T17:12:03.528Z] 17:12:03     INFO -     rsp = 0x0000003a040bf3b0   rip = 0x00007ffa84549750
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -  4  xul.dll + 0xa3a9750
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -     rsp = 0x0000003a040bf3b8   rip = 0x00007ffa84549750
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -  5  xul.dll + 0x198529
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -     rsp = 0x0000003a040bf3f0   rip = 0x00007ffa7a338529
[task 2020-02-26T17:12:03.529Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -  6  xul.dll + 0x9e70000
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -     rsp = 0x0000003a040bf478   rip = 0x00007ffa84010000
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -  7  xul.dll + 0x9e70000
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -     rsp = 0x0000003a040bf4b8   rip = 0x00007ffa84010000
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.530Z] 17:12:03     INFO -  8  xul.dll + 0x55961
[task 2020-02-26T17:12:03.531Z] 17:12:03     INFO -     rsp = 0x0000003a040bf550   rip = 0x00007ffa7a1f5961
[task 2020-02-26T17:12:03.531Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.531Z] 17:12:03     INFO -  9  xul.dll + 0x55c38
[task 2020-02-26T17:12:03.531Z] 17:12:03     INFO -     rsp = 0x0000003a040bf580   rip = 0x00007ffa7a1f5c38
[task 2020-02-26T17:12:03.531Z] 17:12:03     INFO -     Found by: stack scanning
[task 2020-02-26T17:12:03.531Z] 17:12:03     INFO - 
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Intermittent GECKO(2221) | Assertion failure: rc != 0 (destroyed timer off its target thread!), at /builds/worker/workspace/build/src/xpcom/threads/TimerThread.cpp:476 → Intermittent Assertion failure: rc != 0 (destroyed timer off its target thread!), at /builds/worker/workspace/build/src/xpcom/threads/TimerThread.cpp:476
Status: REOPENED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
See Also: → 1729964
You need to log in before you can comment on or make changes to this bug.