Closed Bug 1437751 Opened 6 years ago Closed 5 years ago

Intermittent GECKO(7720) | Assertion failure: rc != 0 (destroyed timer off its target thread!), at z:/build/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)

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=161703654&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/NWLD5uBCQpW-0pQ-yz538Q/runs/0/artifacts/public/logs/live_backing.log

16:05:26     INFO -  801 INFO TEST-OK | dom/workers/test/test_fileBlobPosting.xul | took 5821ms
16:05:29     INFO -  802 INFO Error: Unable to restore focus, expect failures and timeouts.
16:05:29     INFO -  GECKO(7720) | ++DOMWINDOW == 18 (0000023FE4621800) [pid = 7720] [serial = 66] [outer = 0000023FEA6037D0]
16:05:29     INFO -  803 INFO None804 INFO TEST-START | dom/workers/test/test_fileBlobSubWorker.xul
16:05:33     INFO -  GECKO(7720) | ++DOMWINDOW == 19 (0000023FE3510800) [pid = 7720] [serial = 67] [outer = 0000023FEA6037D0]
16:05:33     INFO -  GECKO(7720) | MEMORY STAT | vsize 2099025MB | vsizeMaxContiguous 129720484MB | residentFast 286MB | heapAllocated 87MB
16:05:35     INFO -  805 INFO TEST-OK | dom/workers/test/test_fileBlobSubWorker.xul | took 5873ms
16:05:38     INFO -  806 INFO Error: Unable to restore focus, expect failures and timeouts.
16:05:38     INFO -  GECKO(7720) | ++DOMWINDOW == 20 (0000023FE58A1000) [pid = 7720] [serial = 68] [outer = 0000023FEA6037D0]
16:05:39     INFO -  807 INFO None808 INFO TEST-START | dom/workers/test/test_filePosting.xul
16:05:42     INFO -  GECKO(7720) | ++DOMWINDOW == 21 (0000023FE3DB9400) [pid = 7720] [serial = 69] [outer = 0000023FEA6037D0]
16:05:42     INFO -  GECKO(7720) | MEMORY STAT | vsize 2099023MB | vsizeMaxContiguous 129720484MB | residentFast 284MB | heapAllocated 87MB
16:05:43     INFO -  GECKO(7720) | Assertion failure: rc != 0 (destroyed timer off its target thread!), at z:/build/build/src/xpcom/threads/TimerThread.cpp:476
16:05:55     INFO -  GECKO(7720) | #01: nsThread::ProcessNextEvent(bool,bool *) [xpcom/threads/nsThread.cpp:1041]
16:05:55     INFO -  GECKO(7720) | #02: NS_ProcessNextEvent(nsIThread *,bool) [xpcom/threads/nsThreadUtils.cpp:517]
16:05:55     INFO -  GECKO(7720) | #03: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:364]
16:05:55     INFO -  GECKO(7720) | #04: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:320]
16:05:55     INFO -  GECKO(7720) | #05: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:300]
16:05:55     INFO -  GECKO(7720) | #06: nsThread::ThreadFunc(void *) [xpcom/threads/nsThread.cpp:425]
16:05:55     INFO -  GECKO(7720) | #07: PR_NativeRunThread [nsprpub/pr/src/threads/combined/pruthr.c:406]
16:05:55     INFO -  GECKO(7720) | #08: pr_root [nsprpub/pr/src/md/windows/w95thred.c:138]
16:05:55     INFO -  GECKO(7720) | #09: ucrtbase.dll + 0x20369
16:05:55     INFO -  GECKO(7720) | #10: KERNEL32.DLL + 0x12774
16:05:55     INFO -  GECKO(7720) | #11: ntdll.dll + 0x70d61
16:05:55     INFO -  TEST-INFO | Main app process: exit 1
16:05:55     INFO -  Buffered messages logged at 16:05:42
16:05:55     INFO -  809 INFO Error: Unable to restore focus, expect failures and timeouts.
16:05:55     INFO -  810 INFO TEST-PASS | dom/workers/test/test_filePosting.xul | size of file posted from worker does not match file posted to worker.
16:05:55     INFO -  811 INFO TEST-PASS | dom/workers/test/test_filePosting.xul | type of file posted from worker does not match file posted to worker.
16:05:55     INFO -  812 INFO TEST-PASS | dom/workers/test/test_filePosting.xul | name of file posted from worker does not match file posted to worker.
16:05:55     INFO -  813 INFO TEST-PASS | dom/workers/test/test_filePosting.xul | size of file posted from worker does not match file posted to worker.
16:05:55     INFO -  814 INFO TEST-PASS | dom/workers/test/test_filePosting.xul | type of file posted from worker does not match file posted to worker.
16:05:55     INFO -  815 INFO TEST-PASS | dom/workers/test/test_filePosting.xul | name of file posted from worker does not match file posted to worker.
16:05:55     INFO -  Buffered messages finished
16:05:55    ERROR -  816 ERROR TEST-UNEXPECTED-FAIL | dom/workers/test/test_filePosting.xul | application terminated with exit code 1
16:05:55     INFO -  runtests.py | Application ran for: 0:02:10.752000
16:05:55     INFO -  zombiecheck | Reading PID log: c:\users\genericworker\appdata\local\temp\tmpdrsnx9pidlog
16:05:55     INFO -  mozcrash Copy/paste: Z:\task_1518450595\build\win32-minidump_stackwalk.exe c:\users\genericworker\appdata\local\temp\tmpu3lfjd.mozrunner\minidumps\81fc84eb-5b57-43b5-b8a8-1012d73d54e7.dmp Z:\task_1518450595\build\symbols
16:06:08     INFO -  mozcrash Saved minidump as Z:\task_1518450595\build\blobber_upload_dir\81fc84eb-5b57-43b5-b8a8-1012d73d54e7.dmp
16:06:08     INFO -  mozcrash Saved app info as Z:\task_1518450595\build\blobber_upload_dir\81fc84eb-5b57-43b5-b8a8-1012d73d54e7.extra
16:06:08  WARNING -  PROCESS-CRASH | dom/workers/test/test_filePosting.xul | application crashed [@ TimerThread::Run()]
16:06:08     INFO -  Crash dump filename: c:\users\genericworker\appdata\local\temp\tmpu3lfjd.mozrunner\minidumps\81fc84eb-5b57-43b5-b8a8-1012d73d54e7.dmp
16:06:08     INFO -  Operating system: Windows NT
16:06:08     INFO -                    10.0.15063
16:06:08     INFO -  CPU: amd64
16:06:08     INFO -       family 6 model 63 stepping 2
16:06:08     INFO -       8 CPUs
16:06:08     INFO -  GPU: UNKNOWN
16:06:08     INFO -  Crash reason:  EXCEPTION_BREAKPOINT
16:06:08     INFO -  Crash address: 0x7ff84928bdef
16:06:08     INFO -  Process uptime: 119 seconds
16:06:08     INFO -  Thread 17 (crashed)
16:06:08     INFO -   0  xul.dll!TimerThread::Run() [TimerThread.cpp:e1daef0276f6506fd41a31fe2f9a10e7187c2934 : 476 + 0x1b]
16:06:08     INFO -      rax = 0x0000000000000000   rdx = 0x000000c0521ff318
16:06:08     INFO -      rcx = 0x00000000ffffffff   rbx = 0x00000000000001dc
16:06:08     INFO -      rsi = 0x0000023fd940f1e8   rdi = 0x0000000000000003
16:06:08     INFO -      rbp = 0x000000c0521ff369   rsp = 0x000000c0521ff300
16:06:08     INFO -       r8 = 0x000000c0521ff310    r9 = 0x000000c0521ff308
16:06:08     INFO -      r10 = 0x0000000000000000   r11 = 0x000000c0521fa860
16:06:08     INFO -      r12 = 0x0000000000000001   r13 = 0x0000000000000000
16:06:08     INFO -      r14 = 0x0000023fd940f120   r15 = 0x00000000ffffffff
16:06:08     INFO -      rip = 0x00007ff84928bdef
16:06:08     INFO -      Found by: given as instruction pointer in context
16:06:08     INFO -   1  xul.dll!nsThread::ProcessNextEvent(bool,bool *) [nsThread.cpp:e1daef0276f6506fd41a31fe2f9a10e7187c2934 : 1040 + 0x14]
16:06:08     INFO -      rbx = 0x00000000000001dc   rbp = 0x000000c0521ff369
16:06:08     INFO -      rsp = 0x000000c0521ff3d0   r12 = 0x0000000000000001
16:06:08     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023fd940f120
16:06:08     INFO -      r15 = 0x00000000ffffffff   rip = 0x00007ff8492872a2
16:06:08     INFO -      Found by: call frame info
16:06:08     INFO -   2  xul.dll!NS_ProcessNextEvent(nsIThread *,bool) [nsThreadUtils.cpp:e1daef0276f6506fd41a31fe2f9a10e7187c2934 : 517 + 0x11]
16:06:08     INFO -      rbx = 0x00000000000001dc   rbp = 0x000000c0521ff369
16:06:08     INFO -      rsp = 0x000000c0521ffa90   r12 = 0x0000000000000001
16:06:08     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023fd940f120
16:06:08     INFO -      r15 = 0x00000000ffffffff   rip = 0x00007ff849295533
16:06:08     INFO -      Found by: call frame info
16:06:08     INFO -   3  xul.dll!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate *) [MessagePump.cpp:e1daef0276f6506fd41a31fe2f9a10e7187c2934 : 364 + 0xa]
16:06:08     INFO -      rbx = 0x00000000000001dc   rbp = 0x000000c0521ff369
16:06:08     INFO -      rsp = 0x000000c0521ffad0   r12 = 0x0000000000000001
16:06:08     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023fd940f120
16:06:08     INFO -      r15 = 0x00000000ffffffff   rip = 0x00007ff8497dddce
16:06:08     INFO -      Found by: call frame info
16:06:08     INFO -   4  xul.dll!MessageLoop::RunHandler() [message_loop.cc:e1daef0276f6506fd41a31fe2f9a10e7187c2934 : 319 + 0x5]
16:06:08     INFO -      rbx = 0x00000000000001dc   rbp = 0x000000c0521ff369
16:06:08     INFO -      rsp = 0x000000c0521ffb10   r12 = 0x0000000000000001
16:06:08     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023fd940f120
16:06:08     INFO -      r15 = 0x00000000ffffffff   rip = 0x00007ff8497a593e
16:06:08     INFO -      Found by: call frame info
16:06:08     INFO -   5  xul.dll!MessageLoop::Run() [message_loop.cc:e1daef0276f6506fd41a31fe2f9a10e7187c2934 : 299 + 0x8]
16:06:08     INFO -      rbx = 0x00000000000001dc   rbp = 0x000000c0521ff369
16:06:08     INFO -      rsp = 0x000000c0521ffb40   r12 = 0x0000000000000001
16:06:08     INFO -      r13 = 0x0000000000000000   r14 = 0x0000023fd940f120
16:06:08     INFO -      r15 = 0x00000000ffffffff   rip = 0x00007ff8497a5512
16:06:08     INFO -      Found by: call frame info
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=190852118&repo=mozilla-central&lineNumber=14470

11:30:40     INFO - [Parent 5908, IPDL Background] WARNING: The service must exist if we want to close an existing MessagePort.: file z:/build/build/src/dom/messagechannel/MessagePortParent.cpp, line 173
11:30:40     INFO - IPDL protocol error: Handler returned error code!
11:30:40     INFO - ###!!! [Parent][DispatchAsyncMessage] Error: PBackground::Msg_MessagePortForceClose Processing error: message was deserialized, but the handler returned false (indicating failure)
11:30:40     INFO - [Parent 5908, IPDL Background] WARNING: The service must exist if we want to close an existing MessagePort.: file z:/build/build/src/dom/messagechannel/MessagePortParent.cpp, line 173
11:30:40     INFO - IPDL protocol error: Handler returned error code!
11:30:40     INFO - ###!!! [Parent][DispatchAsyncMessage] Error: PBackground::Msg_MessagePortForceClose Processing error: message was deserialized, but the handler returned false (indicating failure)
11:30:40     INFO - [Parent 5908, IPDL Background] WARNING: The service must exist if we want to close an existing MessagePort.: file z:/build/build/src/dom/messagechannel/MessagePortParent.cpp, line 173
11:30:40     INFO - IPDL protocol error: Handler returned error code!
11:30:40     INFO - ###!!! [Parent][DispatchAsyncMessage] Error: PBackground::Msg_MessagePortForceClose Processing error: message was deserialized, but the handler returned false (indicating failure)
11:30:40     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
11:30:40     INFO - ++DOMWINDOW == 172 (0000026602572C00) [pid = 6072] [serial = 2255] [outer = 000002660069EA00]
11:30:40     INFO - [Parent 5908, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
11:30:40     INFO - [Parent 5908, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
11:30:40     INFO - [Parent 5908, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
11:30:40     INFO - [Parent 5908, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
11:30:40     INFO - Assertion failure: rc != 0 (destroyed timer off its target thread!), at z:/build/build/src/xpcom/threads/TimerThread.cpp:487
11:30:40     INFO - #01: NS_ProcessNextEvent(nsIThread *,bool) [xpcom/threads/nsThreadUtils.cpp:519]
11:30:40     INFO - 
11:30:40     INFO - #02: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate *) [ipc/glue/MessagePump.cpp:364]
11:30:40     INFO - 
11:30:40     INFO - #03: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:325]
11:30:40     INFO - 
11:30:40     INFO - #04: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:319]
11:30:40     INFO - 
11:30:40     INFO - #05: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:299]
11:30:40     INFO - 
11:30:40     INFO - #06: nsThread::ThreadFunc(void *) [xpcom/threads/nsThread.cpp:469]
11:30:40     INFO - 
11:30:40     INFO - #07: PR_NativeRunThread [nsprpub/pr/src/threads/combined/pruthr.c:406]
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE
See Also: → 1729964
You need to log in before you can comment on or make changes to this bug.