Closed Bug 1667760 Opened 4 years ago Closed 2 months ago

Intermittent /sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart | application crashed [@ mozilla::LazyIdleThread::ShutdownThread] | After Assertion failure: !mThreadIsShuttingDown (Huh?!)

Categories

(Firefox :: Session Restore, defect)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=316896830&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aLfTMtrlQrKcYjEt_zoGQw/runs/0/artifacts/public/logs/live_backing.log


[task 2020-09-28T10:21:34.867Z] 10:21:34     INFO -  [Child 1390, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-09-28T10:21:34.867Z] 10:21:34     INFO -  [Child 1390, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4287
[task 2020-09-28T10:21:35.285Z] 10:21:35     INFO -  Assertion failure: !mThreadIsShuttingDown (Huh?!), at /builds/worker/checkouts/gecko/xpcom/threads/LazyIdleThread.cpp:255
[task 2020-09-28T10:21:35.285Z] 10:21:35     INFO -  #01: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x179222]
[task 2020-09-28T10:21:35.285Z] 10:21:35     INFO -  #02: workerlz4_maxCompressedSize[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x59f8ac6]
[task 2020-09-28T10:21:35.285Z] 10:21:35     INFO -  #03: workerlz4_maxCompressedSize[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x5a49197]
[task 2020-09-28T10:21:35.285Z] 10:21:35     INFO -  #04: nsXPTCStubBase::Stub249()[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x2536f9]
[task 2020-09-28T10:21:35.285Z] 10:21:35     INFO -  #05: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x19d5c2]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #06: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x19c1ec]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #07: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x17a67d]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #08: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x17b932]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #09: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x1937ed]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #10: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x19330b]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #11: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x19d5c2]
[task 2020-09-28T10:21:35.286Z] 10:21:35     INFO -  #12: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x1a33bc]
[task 2020-09-28T10:21:35.287Z] 10:21:35     INFO -  #13: nsXPTCStubBase::Stub249()[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x9ca918]
[task 2020-09-28T10:21:35.287Z] 10:21:35     INFO -  #14: nsXPTCStubBase::Stub249()[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x95b74e]
[task 2020-09-28T10:21:35.288Z] 10:21:35     INFO -  #15: NS_NewLocalFileWithCFURL[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL +0x199a0d]
[task 2020-09-28T10:21:35.288Z] 10:21:35     INFO -  #16: PR_GetThreadPrivate[/Users/cltbld/tasks/task_1601286472/build/application/Firefox NightlyDebug.app/Contents/MacOS/libnss3.dylib +0x178b81]
[task 2020-09-28T10:21:35.288Z] 10:21:35     INFO -  #17: _pthread_body[/usr/lib/system/libsystem_pthread.dylib +0x32eb]
[task 2020-09-28T10:21:35.289Z] 10:21:35     INFO -  #18: _pthread_start[/usr/lib/system/libsystem_pthread.dylib +0x6249]
[task 2020-09-28T10:21:35.583Z] 10:21:35     INFO -  Exiting due to channel error.
[task 2020-09-28T10:22:44.266Z] 10:22:44     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YkEVyRiEQzyNvQn83mNYtA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2020-09-28T10:22:53.378Z] 10:22:53     INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1601286472/fetches/minidump_stackwalk/minidump_stackwalk /Users/cltbld/tasks/task_1601286472/build/tmp3UZ16O.mozrunner/minidumps/69022B4B-7212-4D0A-975E-FDEBD969767E.dmp /var/folders/rc/4rg7k55s6r9d732gj46h8t0m000017/T/tmppBzGnx
[task 2020-09-28T10:22:58.433Z] 10:22:58     INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1601286472/build/blobber_upload_dir/69022B4B-7212-4D0A-975E-FDEBD969767E.dmp
[task 2020-09-28T10:22:58.433Z] 10:22:58     INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1601286472/build/blobber_upload_dir/69022B4B-7212-4D0A-975E-FDEBD969767E.extra
[task 2020-09-28T10:22:58.605Z] 10:22:58     INFO - PROCESS-CRASH | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart | application crashed [@ mozilla::LazyIdleThread::ShutdownThread()]
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Mozilla crash reason: MOZ_ASSERT(!mThreadIsShuttingDown) (Huh?!)
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Crash dump filename: /Users/cltbld/tasks/task_1601286472/build/tmp3UZ16O.mozrunner/minidumps/69022B4B-7212-4D0A-975E-FDEBD969767E.dmp
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Operating system: Mac OS X
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO -                   10.14.5 18F132
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - CPU: amd64
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO -      family 6 model 69 stepping 1
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO -      4 CPUs
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - 
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - GPU: UNKNOWN
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - 
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Crash reason:  EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Crash address: 0x0
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Process uptime: 17 seconds
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - 
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO - Thread 57 (crashed)
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO -  0  XUL!mozilla::LazyIdleThread::ShutdownThread() [LazyIdleThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 255 + 0x29]
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO -     rax = 0x000000010b2bf5ea   rdx = 0x0000000000000000
[task 2020-09-28T10:22:58.606Z] 10:22:58     INFO -     rcx = 0x0000000101158600   rbx = 0x000000011f8dbc00
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     rsi = 0x00000000000120a8   rdi = 0x00007fffb2131028
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     rbp = 0x0000700008431f90   rsp = 0x0000700008431ec0
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -      r8 = 0x00000000000130a8    r9 = 0x00007fffb2131048
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     r10 = 0x0000000000000000   r11 = 0x00007fffb2131040
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     r12 = 0xaaaaaaaaaaaaaaaa   r13 = 0x000000011faa02e0
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     r14 = 0x0000700008431f08   r15 = 0x000000011f8dbc40
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     rip = 0x000000010186cbcf
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     Found by: given as instruction pointer in context
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -  1  XUL!mozilla::LazyIdleThread::Shutdown() [LazyIdleThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 482 + 0x8]
[task 2020-09-28T10:22:58.607Z] 10:22:58     INFO -     rbp = 0x0000700008431fb0   rsp = 0x0000700008431fa0
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rip = 0x000000010186b222
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -  2  XUL!mozilla::safebrowsing::Classifier::FlushAndDisableAsyncUpdate() [Classifier.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 708 + 0x6]
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rbp = 0x0000700008431fd0   rsp = 0x0000700008431fc0
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rip = 0x00000001070eaac6
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -  3  XUL!mozilla::detail::RunnableMethodImpl<RefPtr<nsUrlClassifierDBServiceWorker>, void (nsUrlClassifierDBServiceWorker::*)(), true, (mozilla::RunnableKind)0, >::Run() [nsThreadUtils.h:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 1240 + 0x17]
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rbp = 0x0000700008431fe0   rsp = 0x0000700008431fe0
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rip = 0x000000010713b197
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -  4  XUL!mozilla::SyncRunnable::Run() [SyncRunnable.h:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 106 + 0xf]
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rbp = 0x0000700008432000   rsp = 0x0000700008431ff0
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rip = 0x00000001019456f9
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -  5  XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 1234 + 0x16]
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rbp = 0x0000700008432520   rsp = 0x0000700008432010
[task 2020-09-28T10:22:58.608Z] 10:22:58     INFO -     rip = 0x000000010188f5c2
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -  6  XUL!nsThread::Shutdown() [nsThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 898 + 0x62]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x0000700008432560   rsp = 0x0000700008432530
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x000000010188e1ec
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -  7  XUL!mozilla::LazyIdleThread::ShutdownThread() [LazyIdleThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 275 + 0x16]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x0000700008432640   rsp = 0x0000700008432570
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x000000010186c67d
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -  8  XUL!mozilla::LazyIdleThread::Notify(nsITimer*) [LazyIdleThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 538 + 0x8]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x0000700008432660   rsp = 0x0000700008432650
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x000000010186d932
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -  9  XUL!nsTimerImpl::Fire(int) [nsTimerImpl.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 565 + 0xe]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x00007000084326f0   rsp = 0x0000700008432670
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x00000001018857ed
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO - 10  XUL!nsTimerEvent::Run() [TimerThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 251 + 0x15]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x0000700008432720   rsp = 0x0000700008432700
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x000000010188530b
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO - 11  XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 1234 + 0x16]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x0000700008432c40   rsp = 0x0000700008432730
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x000000010188f5c2
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO - 12  XUL!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 513 + 0xd]
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rbp = 0x0000700008432c70   rsp = 0x0000700008432c50
[task 2020-09-28T10:22:58.609Z] 10:22:58     INFO -     rip = 0x00000001018953bc
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO - 13  XUL!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 332 + 0xd]
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rbp = 0x0000700008432cc0   rsp = 0x0000700008432c80
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rip = 0x00000001020bc918
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO - 14  XUL!MessageLoop::Run() [message_loop.cc:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 309 + 0x5]
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rbp = 0x0000700008432cf0   rsp = 0x0000700008432cd0
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rip = 0x000000010204d74e
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO - 15  XUL!nsThread::ThreadFunc(void*) [nsThread.cpp:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 442 + 0x8]
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rbp = 0x0000700008432ed0   rsp = 0x0000700008432d00
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rip = 0x000000010188ba0d
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO - 16  libnss3.dylib!_pt_root [ptthread.c:9c47bca207be7bdaf827d73a24332e1c2aec2545 : 201 + 0xa]
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rbp = 0x0000700008432f10   rsp = 0x0000700008432ee0
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rip = 0x0000000101578b81
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     Found by: previous frame's frame pointer
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO - 17  libsystem_pthread.dylib!_pthread_body + 0x7e
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rbp = 0x0000700008432f30   rsp = 0x0000700008432f20
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     rip = 0x00007fff7b9bd2eb
[task 2020-09-28T10:22:58.610Z] 10:22:58     INFO -     Found by: previous frame's frame pointer```
Component: XPCOM → Session Restore
Product: Core → Firefox

See also bug 1629251 for the assertion that is raised first, and triggers this crash.

See Also: → 1629251
Severity: normal → S3
Crash Signature: [@ mozilla::LazyIdleThread::ShutdownThread()] → [@ mozilla::LazyIdleThread::ShutdownThread]
Summary: Intermittent /sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart | application crashed [@ mozilla::LazyIdleThread::ShutdownThread()] | After Assertion failure: !mThreadIsShuttingDown (Huh?!) → Intermittent /sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart | application crashed [@ mozilla::LazyIdleThread::ShutdownThread] | After Assertion failure: !mThreadIsShuttingDown (Huh?!)
Status: NEW → RESOLVED
Closed: 2 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.