Opened 4 years ago

Closed 4 years ago

Last modified 4 years ago

#16670 closed bug (fixed)

Tracker - no icons on Desktop, cannot launch apps from menu

Reported by: vidrep Owned by: pulkomandy
Priority: critical Milestone: R1/beta3
Component: Applications/Tracker Version: R1/beta2
Keywords: Cc:
Blocked By: Blocking: #16701
Platform: All

Description

hrev54790 x86_64

Intermittent no icons on Desktop. Cannot launch any apps from menu. Killing Tracker with TeamMonitor will restore normal function. Problem was first seen "about" 4 weeks ago.

Attachments (2)

syslog​ (137.8 KB ) - added by vidrep 4 years ago.
syslog.2​ (161.0 KB ) - added by HAL 4 years ago.
syslog for repeated steps in comment 9

Download all attachments as: .zip

Change History (29)

comment:1 by vidrep, 4 years ago

Opening a Terminal from TeamMonitor, and attaching Debugger to Tracker produces no debug report. Would creating a serial debug log help?

comment:2 by diver, 4 years ago

I *think* had the same thing a few days ago. However, killing Tracker from ProcessController didn't help. Reboot fixed it, tho.

comment:3 by vidrep, 4 years ago

This earlier ticket sounds like the same thing https://dev.haiku-os.org/ticket/16380

comment:4 by nephele, 4 years ago

Not beeing able to launch applications from deskbar sounds like this is not trackers fault. Dissabeling user addons made this go away for me, but it might just have been reboot correlation

comment:5 by diver, 4 years ago

Blocking: 16701 added

comment:6 by pulkomandy, 4 years ago

Milestone: Unscheduled → R1/beta3

comment:7 by waddlesplash, 4 years ago

Priority: normal → critical

Vidrep says this occurs very often for him:

<vidrep_64> There's one issue I'm seeing about 50% of by boots when using 64 bit nightly - no Tracker

I have seen it before, too, but not that frequently. Seems worth investigating. Vidrep, please do collect a serial log; and if you know how, a KDL log of where Debugger is stuck trying to generate a debug report.

comment:8 by HAL, 4 years ago

The no icons on desktop was fixed temporarily for me with hrev54881. Now it is back with hrev54899. I also have to kill tracker to makethem re appear.

comment:9 by X512, 4 years ago

This looks like deadlock in Tracker.

Can you do following steps

  1. Ensure that problem is present.
  2. Enter KDL by Ctrl+Alt+SysRq+D.
  3. Type teams.
  4. Find Tracker team ID and type threads <Tracker team ID>.
  5. Type bt <thread ID> for each thread.
  6. Type es.
  7. Restart Tracker.
  8. Attach /boot/system/var/log/syslog to this ticket.
Version 0, edited 4 years ago by X512 (next)

comment:10 by vidrep, 4 years ago

I've attached my sylog

by vidrep, 4 years ago

Attachment: syslog​ added

comment:11 by pulkomandy, 4 years ago

So here's the situation from this syslog, extracted for easier analysis:

Main thread is trying to lock something to load locale catalogs: 

2250	KERN: frame                       caller             <image>:function + offset
2251	KERN:  0 ffffffff81b62d80 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2252	KERN:  1 ffffffff81b62df0 (+  64) ffffffff80088dc6   <kernel_x86_64> thread_block + 0xf6
2253	KERN:  2 ffffffff81b62e30 (+ 192) ffffffff80070e1d   <kernel_x86_64> switch_sem_etc + 0x5cd
2254	KERN:  3 ffffffff81b62ef0 (+  48) ffffffff800718c6   <kernel_x86_64> _user_acquire_sem_etc + 0x46
2255	KERN:  4 ffffffff81b62f20 (+  16) ffffffff80152bb8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2256	KERN: user iframe at 0xffffffff81b62f30 (end = 0xffffffff81b62ff8)
2257	KERN:  rax 0x11                  rbx 0x10fd26954200        rcx 0xbfd05d2844
2258	KERN:  rdx 0x8                   rsi 0x1                   rdi 0xcdb
2259	KERN:  rbp 0x7f3ecc01a1f0         r8 0x7                    r9 0x0
2260	KERN:  r10 0x7fffffffffffffff    r11 0x3202                r12 0x200
2261	KERN:  r13 0x7fffffffffffffff    r14 0x7f3ecc01a20c        r15 0x0
2262	KERN:  rip 0xbfd05d2844          rsp 0x7f3ecc01a1c8     rflags 0x3202
2263	KERN:  vector: 0x63, error code: 0x0
2264	KERN:  5 ffffffff81b62f30 (+139909806125760) 000000bfd05d2844   <libroot.so> _kern_acquire_sem_etc + 0x0c
2265	KERN:  6 00007f3ecc01a1f0 (+  32) 000001b1e1f1ed8b   <libbe.so> BLocker::Lock() + 0x1b
2266	KERN:  7 00007f3ecc01a210 (+ 304) 000001b1e1edf705   <libbe.so> BPrivate::MutableLocaleRoster::LoadCatalog const(entry_ref const&, char const*, int) + 0x45
2267	KERN:  8 00007f3ecc01a340 (+  80) 000001b1e1ed5bfe   <libbe.so> BCatalog::SetTo(entry_ref const&, char const*, unsigned int) + 0x5e
2268	KERN:  9 00007f3ecc01a390 (+1264) 000001b1e1edd382   <libbe.so> _ZNK13BLocaleRoster35GetAvailableTimeZonesWithRegionInfoEP8BMessage (nearest) + 0x212
2269	KERN: 10 00007f3ecc01a880 (+  48) 000000bfd05d39cd   <libroot.so> __init_once + 0xfd
2270	KERN: 11 00007f3ecc01a8b0 (+  32) 000001b1e1edd0be   <libbe.so> BLocaleRoster::_GetCatalog(BCatalog*, int*) + 0x1e
2271	KERN: 12 00007f3ecc01a8d0 (+  16) 0000005786d13a51   <WebPTranslator> main (nearest) + 0xe1
2272	KERN: 13 00007f3ecc01a8e0 (+ 176) 000001010e3d13cf   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0xb3cf
2273	KERN: 14 00007f3ecc01a990 (+  96) 000001010e3d1e5d   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0xbe5d
2274	KERN: 15 00007f3ecc01a9f0 (+  32) 000001010e3d60d6   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0x100d6
2275	KERN: 16 00007f3ecc01aa10 (+ 432) 000000309bfaf05b   <libtranslation.so> BTranslatorRoster::Private::CreateTranslators(entry_ref const&, int&, BMessage*) + 0x10b
2276	KERN: 17 00007f3ecc01abc0 (+ 320) 000000309bfaf505   <libtranslation.so> BTranslatorRoster::Private::AddPath(char const*, int*) + 0x185
2277	KERN: 18 00007f3ecc01ad00 (+ 128) 000000309bfaf691   <libtranslation.so> BTranslatorRoster::Private::AddDefaKERN: ultPaths() + 0xc1
2278	KERN: 19 00007f3ecc01ad80 (+  32) 000000309bfaf7dd   <libtranslation.so> BTranslatorRoster::AddTranslators(char const*) + 0x3d
2279	KERN: 20 00007f3ecc01ada0 (+  32) 000000309bfaf846   <libtranslation.so> BTranslatorRoster::Default() + 0x56
2280	KERN: 21 00007f3ecc01adc0 (+ 208) 000000309bfac0b5   <libtranslation.so> BTranslationUtils::GetBitmap(BPositionIO*, BTranslatorRoster*) + 0xa5
2281	KERN: 22 00007f3ecc01ae90 (+ 464) 000000309bfac34d   <libtranslation.so> BTranslationUtils::GetBitmapFile(char const*, BTranslatorRoster*) + 0xed
2282	KERN: 23 00007f3ecc01b060 (+  32) 000000309bfac422   <libtranslation.so> BTranslationUtils::GetBitmap(char const*, BTranslatorRoster*) + 0x12
2283	KERN: 24 00007f3ecc01b080 (+ 304) 000000cc66eb85f5   <libtracker.so> BPrivate::BackgroundImage::GetBackgroundImage(BNode const*, bool) + 0x155
2284	KERN: 25 00007f3ecc01b1b0 (+ 144) 000000cc66ec289c   <libtracker.so> BPrivate::BContainerWindow::RestoreStateCommon() + 0x9c
2285	KERN: 26 00007f3ecc01b240 (+  80) 000000cc66ec299e   <libtracker.so> BPrivate::BContainerWindow::RestoreState() + 0x6e
2286	KERN: 27 00007f3ecc01b290 (+5584) 000000cc66ec06e6   <libtracker.so> BPrivate::BContainerWindow::Init(BMessage const*) + 0x646
2287	KERN: 28 00007f3ecc01c860 (+ 176) 000000cc66ecaaf7   <libtracker.so> BPrivate::BDeskWindow::Init(BMessage const*) + 0xc7
2288	KERN: 29 00007f3ecc01c910 (+ 736) 000000cc66f449d0   <libtracker.so> BPrivate::TTracker::TTracker() + 0x2d0
2289	KERN: 30 00007f3ecc01cbf0 (+ 544) 000000f09cdd87fd   <_APP_> main + 0x1d
2290	KERN: 31 00007f3ecc01ce10 (+  48) 000000f09cdd893e   <_APP_> _start + 0x3e
2291	KERN: 32 00007f3ecc01ce40 (+  48) 000001010e3da505   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0x14505
2292	KERN: 33 00007f3ecc01ce70 (+   0) 00007fe7ea965260   <commpage> commpage_thread_exit + 0x00

TrackerTaskLoop is also waiting for something locale kit related: 

2294	KERN:     kernel stack: 0xffffffff81e97000 to 0xffffffff81e9c000
2295	KERN:       user stack: 0x00007fd3479bf000 to 0x00007fd3479ff000
2296	KERN: frame                       caller             <image>:function + offset
2297	KERN:  0 ffffffff81e9bc50 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2298	KERN:  1 ffffffff81e9bcc0 (+  64) ffffffff80088dc6   <kernel_x86_64> thread_block + 0xf6
2299	KERN:  2 ffffffff81e9bd00 (+  80) ffffffff80055855   <kernel_x86_64> ConditionVariableEntry::Wait(unsigned int, long) + 0xf5
2300	KERN:  3 ffffffff81e9bd50 (+ 256) ffffffff80095eba   <kernel_x86_64> _ZL22user_mutex_wait_lockedPimPKcjlRN8BPrivate10AutoLockerI5mutexNS2_12MutexLockingEEERb.isra.22 + 0x13a
2301	KERN:  4 ffffffff81e9be50 (+  48) ffffffff80096255   <kernel_x86_64> _ZL22user_mutex_lock_lockedPimPKcjlRN8BPrivate10AutoLockerI5mutexNS2_12MutexLockingEEE.isra.23 + 0x65
2302	KERN:  5 ffffffff81e9be80 (+ 160) ffffffff80096724   <kernel_x86_64> _user_mutex_lock + 0xd4
2303	KERN:  6 ffffffff81e9bf20 (+  16) ffffffff80152bb8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2304	KERN: user iframe at 0xffffffff81e9bf30 (end = 0xffffffff81e9bff8)
2305	KERN:  rax 0x7                   rbx 0x1010e5e6c28         rcx 0x1010e3dd834
2306	KERN:  rdx 0x0                   rsi 0x1010e3e0c11         rdi 0x1010e5e6c28
2307	KERN:  rbp 0x7fd3479fe0d0         r8 0x10fd26990421         r9 0x5
2308	KERN:  r10 0x0                   r11 0x3246                r12 0x1010e5e6c20
2309	KERN:  r13 0x7fd3479fe188        r14 0x0                   r15 0x7fd3479fe168
2310	KERN:  rip 0x1010e3dd834         rsp 0x7fd3479fe0b8     rflags 0x3246
2311	KERN:  vector: 0x63, error code: 0x0
2312	KERN:  7 ffffffff81e9bf30 (+140547531874720) 000001010e3dd834   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0x17834
2313	KERN:  8 00007fd3479fe0d0 (+  32) 000001010e3dd751   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0x17751
2314	KERN:  9 00007fd3479fe0f0 (+  96) 000001010e3d1dd7   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0xbdd7
2315	KERN: 10 00007fd3479fe150 (+  32) 000001010e3d60d6   </boot/system/runtime_loader@0x000001010e3c6000> <unknown> + 0x100d6
2316	KERN: 11 00007fd3479fe170 (+  64) 000001b1e1edd8f5   <libbe.so> BPrivate::CatalogAddOnInfo::MakeSureItsLoaded() + 0x95
2317	KERN: 12 00007fd3479fe1b0 (+ 304) 000001b1e1edf777   <libbe.so> BPrivate::MutableLocaleRoster::LoadCatalog const(entry_ref const&, char const*, int) + 0xb7
2318	KERN: 13 00007fd3479fe2e0 (+  80) 000001b1e1ed5bfe   <libbe.so> BCatalog::SetTo(entry_ref const&, char const*, unsigned int) + 0x5e
2319	KERN: 14 00007fd3479fe330 (+  64) 000001b1e1ed5e97   <libbe.so> BCatalog::BCatalog(entry_ref const&, char const*, unsigned int) + 0x57
2320	KERN: 15 00007fd3479fe370 (+ 288) 000001b1e1edd6d0   <libbe.so> BLocaleRoster::GetLocalizedFileName(BString&, entry_ref const&, bool) + 0x120
2321	KERN: 16 00007fd3479fe490 (+  32) 000000cc66eff94f   <libtracker.so> BPrivate::Model::CacheLocalizedName() + 0x3f
2322	KERN: 17 00007fd3479fe4b0 (+ 112) 000000cc66f003cc   <libtracker.so> BPrivate::Model::OpenNodeCommon(bool) + 0x11c
2323	KERN: 18 00007fd3479fe520 (+  32) 000000cc66f0052f   <libtracker.so> BPrivate::Model::OpenNode(bool) + 0x2f
2324	KERN: 19 00007fd3479fe540 (+  48) 000000cc66f00774   <libtracker.so> BPrivate::Model::SetTo(BEntry const*, bool, bool) + 0xa4
2325	KERN: 20 00007fd3479fe570 (+ 144) 000000cc66f00b9e   <libtracker.so> BPrivate::Model::Model(entry_ref const*, bool, bool, bool) + 0xae
2326	KERN: 21 00007fd3479fe600 (+ 320) 000000cc66f0617b   <libtracker.so> BPrivate::NodePreloader::PreloadOne(char const*) + 0xdb
2327	KERN: 22 00007fd3479fe740 (+ 128) 000000cc66f06372   <libtracker.so> BPrivate::NodePreloader::Preload() + 0xd2
2328	KERN: 23 00007fd3479fe7c0 (+  32) 000000cc66f5c595   <libtracker.so> BPrivate::Thread::Run() + 0x15
2329	KERN: 24 00007fd3479fe7e0 (+  16) 000000cc66f5c57a   <libtracker.so> BPrivate::SimpleThread::RunBinder(void*) + 0x0a
2330	KERN: 25 00007fd3479fe7f0 (+  32) 000000bfd05d22e9   <libroot.so> _thread_do_exit_work (nearest) + 0x79
2331	KERN: 26 00007fd3479fe810 (+   0) 00007fe7ea965260   <commpage> commpage_thread_exit + 0x00

LaunchLooper is trying to lock a window: 

2333	KERN:     kernel stack: 0xffffffff81e9c000 to 0xffffffff81ea1000
2334	KERN:       user stack: 0x00007fb0640aa000 to 0x00007fb0640ea000
2335	KERN: frame                       caller             <image>:function + offset
2336	KERN:  0 ffffffff81ea0d30 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2337	KERN:  1 ffffffff81ea0da0 (+  80) ffffffff80088f3e   <kernel_x86_64> thread_block_with_timeout + 0x14e
2338	KERN:  2 ffffffff81ea0df0 (+  80) ffffffff80055802   <kernel_x86_64> ConditionVariableEntry::Wait(unsigned int, long) + 0xa2
2339	KERN:  3 ffffffff81ea0e40 (+ 144) ffffffff8006d270   <kernel_x86_64> _get_port_message_info_etc + 0x120
2340	KERN:  4 ffffffff81ea0ed0 (+  80) ffffffff8006e2f6   <kernel_x86_64> _user_port_buffer_size_etc + 0x46
2341	KERN:  5 ffffffff81ea0f20 (+  16) ffffffff80152bb8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2342	KERN: user iframe at 0xffffffff81ea0f30 (end = 0xffffffff81ea0ff8)
2343	KERN:  rax 0xdb                  rbx 0x10fd269520a0        rcx 0xbfd05d34e4
2344	KERN:  rdx 0x7fffffffffffffff    rsi 0x8                   rdi 0x69
2345	KERN:  rbp 0x7fb0640e9f00         r8 0x7fffffffffffffff     r9 0x0
2346	KERN:  r10 0xcfc                 r11 0x3202                r12 0x10fd269520a0
2347	KERN:  r13 0x7fffffffffffffff    r14 0x7fb0640e9f1c        r15 0x7fb0640e9f44
2348	KERN:  rip 0xbfd05d34e4          rsp 0x7fb0640e9ed8     rflags 0x3202
2349	KERN:  vector: 0x63, error code: 0x0
2350	KERN:  6 ffffffff81ea0f30 (+140397685018576) 000000bfd05d34e4   <libroot.so> _kern_port_buffer_size_etc + 0x0c
2351	KERN:  7 00007fb0640e9f00 (+  48) 000001b1e1e00a7a   <libbe.so> BLooper::ReadMessageFromPort(long) + 0x1a
2352	KERN:  8 00007fb0640e9f30 (+  80) 000001b1e1e01fd1   <libbe.so> BLooper::task_looper() + 0x61
2353	KERN:  9 00007fb0640e9f80 (+  32) 000001b1e1e00901   <libbe.so> BLooper::_task0_(void*) + 0x21
2354	KERN: 10 00007fb0640e9fa0 (+  32) 000000bfd05d22e9   <libroot.so> _thread_do_exit_work (nearest) + 0x79
2355	KERN: 11 00007fb0640e9fc0 (+   0) 00007fe7ea965260   <commpage> commpage_thread_exit + 0x00
2356	KERN: kdebug> bt 742stack trace for thread 742 "w>Desktop"
2357	KERN:     kernel stack: 0xffffffff81ec8000 to 0xffffffff81ecd000
2358	KERN:       user stack: 0x00007fe7e81f6000 to 0x00007fe7e8236000
2359	KERN: frame                       caller             <image>:function + offset
2360	KERN:  0 ffffffff81eccd80 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2361	KERN:  1 ffffffff81eccdf0 (+  64) ffffffff80088dc6   <kernel_x86_64> thread_block + 0xf6
2362	KERN:  2 ffffffff81ecce30 (+ 192) ffffffff80070e1d   <kernel_x86_64> switch_sem_etc + 0x5cd
2363	KERN:  3 ffffffff81eccef0 (+  48) ffffffff800718c6   <kernel_x86_64> _user_acquire_sem_etc + 0x46
2364	KERN:  4 ffffffff81eccf20 (+  16) ffffffff80152bb8   <kernel_x86_64> x86_64_syscall_entry + 0KERN: xfe
2365	KERN: user iframe at 0xffffffff81eccf30 (end = 0xffffffff81eccff8)
2366	KERN:  rax 0x11                  rbx 0x7fffffffffffffff    rcx 0xbfd05d2844
2367	KERN:  rdx 0x8                   rsi 0x1                   rdi 0xd0c
2368	KERN:  rbp 0x7fe7e8235e10         r8 0x7fffffffffffffff     r9 0x0
2369	KERN:  r10 0x7fffffffffffffff    r11 0x3202                r12 0xd0c
2370	KERN:  r13 0x10fd26987960        r14 0x2e6                 r15 0x7fe7e8235e43
2371	KERN:  rip 0xbfd05d2844          rsp 0x7fe7e8235dd8     rflags 0x3202
2372	KERN:  vector: 0x63, error code: 0x0
2373	KERN:  5 ffffffff81eccf30 (+140636123991776) 000000bfd05d2844   <libroot.so> _kern_acquire_sem_etc + 0x0c
2374	KERN:  6 00007fe7e8235e10 (+  16) 000001b1e1e008d8   <libbe.so> BLooper::Lock() + 0x18
2375	KERN:  7 00007fe7e8235e20 (+ 144) 000001b1e1ebe858   <libbe.so> BWindow::task_looper() + 0xe8
2376	KERN:  8 00007fe7e8235eb0 (+  32) 000001b1e1e00901   <libbe.so> BLooper::_task0_(void*) + 0x21
2377	KERN:  9 00007fe7e8235ed0 (+  32) 000000bfd05d22e9   <libroot.so> _thread_do_exit_work (nearest) + 0x79
2378	KERN: 10 00007fe7e8235ef0 (+   0) 00007fe7ea965260   <commpage> commpage_thread_exit + 0x00

PathMonitor looper is idle: 

2380	KERN:     kernel stack: 0xffffffff81ea6000 to 0xffffffff81eab000
2381	KERN:       user stack: 0x00007f210acc0000 to 0x00007f210ad00000
2382	KERN: frame                       caller             <image>:function + offset
2383	KERN:  0 ffffffff81eaad30 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2384	KERN:  1 ffffffff81eaada0 (+  80) ffffffff80088f3e   <kernel_x86_64> thread_block_with_timeout + 0x14e
2385	KERN:  2 ffffffff81eaadf0 (+  80) ffffffff80055802   <kernel_x86_64> ConditionVariableEntry::Wait(unsigned int, long) + 0xa2
2386	KERN:  3 ffffffff81eaae40 (+ 144) ffffffff8006d270   <kernel_x86_64> _get_port_message_info_etc + 0x120
2387	KERN:  4 ffffffff81eaaed0 (+  80) ffffffff8006e2f6   <kernel_x86_64> _user_port_buffer_size_etc + 0x46
2388	KERN:  5 ffffffff81eaaf20 (+  16) ffffffff80152bb8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2389	KERN: user iframe at 0xffffffff81eaaf30 (end = 0xffffffff81eaaff8)
2390	KERN:  rax 0xdb                  rbx 0x10fd269510a0        rcx 0xbfd05d34e4
2391	KERN:  rdx 0x7fffffffffffffff    rsi 0x8                   rdi 0x8e
2392	KERN:  rbp 0x7f210acffc10         r8 0x7fffffffffffffff     r9 0x0
2393	KERN:  r10 0xbfd05d2844          r11 0x3202                r12 0x10fd269510a0
2394	KERN:  r13 0x7fffffffffffffff    r14 0x7f210acffc2c        r15 0x7f210acffc54
2395	KERN:  rip 0xbfd05d34e4          rsp 0x7f210acffbe8     rflags 0x3202
2396	KERN:  vector: 0x63, error code: 0x0
2397	KERN:  6 ffffffff81eaaf30 (+139782007377120) 000000bfd05d34e4   <libroot.so> _kern_port_buffer_size_etc + 0x0c
2398	KERN:  7 00007f210acffc10 (+  48) 000001b1e1e00a7a   <libbe.so> BLooper::ReadMessageFromPort(long) + 0x1a
2399	KERN:  8 00007f210acffc40 (+  80) 000001b1e1e01fd1   <libbe.so> BLooper::task_looper() + 0x61
2400	KERN:  9 00007f210acffc90 (+  32) 000001b1e1e00901   <libbe.so> BLooper::_task0_(void*) + 0x21
2401	KERN: 10 00007f210acffcb0 (+  32) 000000bfd05d22e9   <libroot.so> _thread_do_exit_work (nearest) + 0x79
2402	KERN: 11 00007f210acffcd0 (+   0) 00007fe7ea965260   <commpage> commpage_thread_exit + 0x00

add poses is trying to lock a window: 

2404	KERN:     kernel stack: 0xffffffff81b63000 to 0xffffffff81b68000
2405	KERN:       user stack: 0x00007faec5e01000 to 0x00007faec5e41000
2406	KERN: frame                       caller             <image>:function + offset
2407	KERN:  0 ffffffff81b67d80 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2408	KERN:  1 ffffffff81b67df0 (+  64) ffffffff80088dc6   <kernel_x86_64> thread_block + 0xf6
2409	KERN:  2 ffffffff81b67e30 (+ 192) ffffffff80070e1d   <kernel_x86_64> switch_sem_etc + 0x5cd
2410	KERN:  3 ffffffff81b67ef0 (+  48) ffffffff800718c6   <kernel_x86_64> _user_acquire_sem_etc + 0x46
2411	KERN:  4 ffffffff81b67f20 (+  16) ffffffff80152bb8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2412	KERN: user iframe at 0xffffffff81b67f30 (end = 0xffffffff81b67ff8)
2413	KERN:  rax 0x11                  rbx 0x7fffffffffffffff    rcx 0xbfd05d2844
2414	KERN:  rdx 0x8                   rsi 0x1                   rdi 0xd0c
2415	KERN:  rbp 0x7faec5e403b0         r8 0x7fffffffffffffff     r9 0x5
2416	KERN:  r10 0x7fffffffffffffff    r11 0x3202                r12 0xd0c
2417	KERN:  r13 0x10fd26987960        r14 0x330                 r15 0x0
2418	KERN:  rip 0xbfd05d2844          rsp 0x7faec5e40378     rflags 0x3202
2419	KERN:  vector: 0x63, error code: 0x0
2420	KERN:  5 ffffffff81b67f30 (+140390739838080) 000000bfd05d2844   <libroot.so> _kern_acquire_sem_etc + 0x0c
2421	KERN:  6 00007faec5e403b0 (+  16) 000001b1e1e008d8   <libbe.so> BLooper::Lock() + 0x18
2422	KERN:  7 00007faec5e403c0 (+  48) 000001b1e1e0c63b   <libbe.so> BMessenger::LockTarget const() + 0x2b
2423	KERN:  8 00007faec5e403f0 (+1472) 000000cc66f24b4e   <libtracker.so> BPrivate::BPoseView::AddPosesTask(void*) + 0x9e
2424	KERN:  9 00007faec5e409b0 (+  32) 000000bfd05d22e9   <libroot.so> _thread_do_exit_work (nearest) + 0x79
2425	KERN: 10 00007faec5e409d0 (+   0) 00007fe7ea965260   <commpage> commpage_thread_exit + 0x00

comment:12 by pulkomandy, 4 years ago

Does the issue disappear if you disable "translate folder and application names" in locale preferences?

Last edited 4 years ago by pulkomandy (previous) (diff)

comment:13 by pulkomandy, 4 years ago

I think there is a deadlock between these first two threads.

The main Tracker one calls BTranslatorRoster::Private::CreateTranslators. This calls load_add_on, and one of the add-ons (I suspect in its static constructed objects) uses the locale kit. In BPrivate::MutableLocaleRoster::LoadCatalog it needs to lock the locale roster data.

Meanwhile, the TrackerTaskLoop wants to translate some application names. To do this, it first locks the locale roster lock, and then calls load_add_on to load the catalog add-ons.

Since load_add_on needs some lock inside the runtime loader, we now have one thread holding the load_add_on lock and waiting for the locale roster lock. And the other thread holding the locale roster lock, and waiting for the load_add_ons one. They now wait for each other indefinitely.

comment:14 by pulkomandy, 4 years ago

Owner: changed from nobody to pulkomandy
Status: new → in-progress

comment:15 by pulkomandy, 4 years ago

Should be fixed with ​https://review.haiku-os.org/c/haiku/+/3661 . Can you try?

comment:16 by pulkomandy, 4 years ago

Resolution: → fixed
Status: in-progress → closed

Fixed in hrev54910.

comment:17 by HAL, 4 years ago

In hrev54912 x86_64 I saw no icons on desktop after starting Haiku at first restart after update but on second restart icons show on destop.

Last edited 4 years ago by HAL (previous) (diff)

comment:18 by HAL, 4 years ago

Every restart after the second restart icons sometimes appear, other times they don't.

Last edited 4 years ago by HAL (previous) (diff)

comment:19 by diver, 4 years ago

Can you repeat steps from comment 9?

by HAL, 4 years ago

Attachment: syslog.2​ added

syslog for repeated steps in comment 9

comment:20 by X512, 4 years ago

syslog for repeated steps in comment 9

Still seems locale issue. Also something strange is happening with USB XHCI. And ​with SCSI disk.

comment:21 by HAL, 4 years ago

Disabling translate application and folder names in Locale worked, after 4 restarts icons appeared each time.

Last edited 4 years ago by HAL (previous) (diff)

comment:22 by vidrep, 4 years ago

I just had it happen again, after almost 2 weeks of it seemingly being fixed. hrev54945 x86_64

comment:23 by X512, 4 years ago

Resolution: fixed
Status: closed → reopened

comment:24 by pulkomandy, 4 years ago

Main thread is at the same place (no surprise, no code was changed on that side): 

KERN: kdebug> bt 820stack trace for thread 820 "Tracker"
2901	KERN:     kernel stack: 0xffffffff81b32000 to 0xffffffff81b37000
2902	KERN:       user stack: 0x00007fab28af0000 to 0x00007fab29af0000
2903	KERN: frame                       caller             <image>:function + offset
2904	KERN:  0 ffffffff81b36d80 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2905	KERN:  1 ffffffff81b36df0 (+  64) ffffffff80088dc6   <kernel_x86_64> thread_block + 0xf6
2906	KERN:  2 ffffffff81b36e30 (+ 192) ffffffff80070e1d   <kernel_x86_64> switch_sem_etc + 0x5cd
2907	KERN:  3 ffffffff81b36ef0 (+  48) ffffffff800718c6   <kernel_x86_64> _user_acquire_sem_etc + 0x46
2908	KERN:  4 ffffffff81b36f20 (+  16) ffffffff80152ec8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2909	KERN: user iframe at 0xffffffff81b36f30 (end = 0xffffffff81b36ff8)
2910	KERN:  rax 0x11                  rbx 0x118730f7e200        rcx 0x26550ef844
2911	KERN:  rdx 0x8                   rsi 0x1                   rdi 0xe97
2912	KERN:  rbp 0x7fab29aec510         r8 0x7                    r9 0x0
2913	KERN:  r10 0x7fffffffffffffff    r11 0x3202                r12 0x300
2914	KERN:  r13 0x7fffffffffffffff    r14 0x7fab29aec52c        r15 0x0
2915	KERN:  rip 0x26550ef844          rsp 0x7fab29aec4e8     rflags 0x3202
2916	KERN:  vector: 0x63, error code: 0x0
2917	KERN:  5 ffffffff81b36f30 (+140375234401760) 00000026550ef844   <libroot.so> _kern_acquire_sem_etc + 0x0c
2918	KERN:  6 00007fab29aec510 (+  32) 00000150b89bfd6b   <libbe.so> BLocker::Lock() + 0x1b
2919	KERN:  7 00007fab29aec530 (+ 304) 00000150b8980715   <libbe.so> BPrivate::MutableLocaleRoster::LoadCatalog const(entry_ref const&, char const*, int) + 0x45
2920	KERN:  8 00007fab29aec660 (+  80) 00000150b8976bfe   <libbe.so> BCatalog::SetTo(entry_ref const&, char const*, unsigned int) + 0x5e
2921	KERN:  9 00007fab29aec6b0 (+1264) 00000150b897e372   <libbe.so> _ZNK13BLocaleRoster35GetAvailableTimeZonesWithRegionInfoEP8BMessage (nearest) + 0x212
2922	KERN: 10 00007fab29aecba0 (+  48) 00000026550f09cd   <libroot.so> __init_once + 0xfd
2923	KERN: 11 00007fab29aecbd0 (+  32) 00000150b897e0ae   <libbe.so> BLocaleRoster::_GetCatalog(BCatalog*, int*) + 0x1e
2924	KERN: 12 00007fab29aecbf0 (+  16) 000001a35a2a1a51   <WebPTranslator> main (nearest) + 0xe1
2925	KERN: 13 00007fab29aecc00 (+ 176) 0000019073efb3cf   </boot/system/runtime_loader@0x0000019073ef0000> <unknown> + 0xb3cf
2926	KERN: 14 00007fab29aeccb0 (+  96) 0000019073efbe5d   </boot/system/runtime_loader@0x0000019073ef0000> <unknown> + 0xbe5d
2927	KERN: 15 00007fab29aecd10 (+  32) 0000019073f000d6   </boot/system/runtime_loader@0x0000019073ef0000> <unknown> + 0x100d6
2928	KERN: 16 00007fab29aecd30 (+ 432) 0000003c3c1e105b   <libtranslation.so> BTranslatorRoster::Private::CreateTranslators(entry_ref const&, int&, BMessage*) + 0x10b
2929	KERN: 17 00007fab29aecee0 (+ 320) 0000003c3c1e1505   <libtranslation.so> BTranslatorRoster::Private::AddPath(char const*, int*) + 0x185
2930	KERN: 18 00007fab29aed020 (+ 128) 0000003c3c1e1691   <libtranslation.so> BTranslatorRoster::Private::AddDefaultPaths() + 0xc1
2931	KERN: 19 00007fab29aed0a0 (+  32) 0000003c3c1e17dd   <libtranslation.so> BTranslatorRoster::AddTranslators(char const*) + 0x3d
2932	KERN: 20 00007fab29aed0c0 (+  32) 0000003c3c1e1846   <libtranslation.so> BTranslatorRoster::Default() + 0x56
2933	KERN: 21 00007fab29aed0e0 (+ 208) 0000003c3c1de0b5   <libtranslation.so> BTranslationUtils::GetBitmap(BPositionIO*, BTranslatorRoster*) + 0xa5
2934	KERN: 22 00007fab29aed1b0 (+ 464) 0000003c3c1de34d   <libtranslation.so> BTranslationUtils::GetBitmapFile(char const*, BTranslatorRoster*) + 0xed
2935	KERN: 23 00007fab29aed380 (+  32) 0000003c3c1de422   <libtranslation.so> BTranslationUtils::GetBitmap(char const*, BTranslatorRoster*) + 0x12
2936	KERN: 24 00007fab29aed3a0 (+ 304) 00000147a55db5f5   <libtracker.so> BPrivate::BackgroundImage::GetBackgroundImage(BNode const*, bool) + 0x155
2937	KERN: 25 00007fab29aed4d0 (+ 144) 00000147a55e589c   <libtracker.so> BPrivate::BContainerWindow::RestoreStateCommon() + 0x9c
2938	KERN: 26 00007fab29aed560 (+  80) 00000147a55e599e   <libtracker.so> BPrivate::BContainerWindow::RestoreState() + 0x6e
2939	KERN: 27 00007fab29aed5b0 (+5584) 00000147a55e36e6   <libtracker.so> BPrivate::BContainerWindow::Init(BMessage const*) + 0x646
2940	KERN: 28 00007fab29aeeb80 (+ 176) 00000147a55edaf7   <libtracker.so> BPrivate::BDeskWindow::Init(BMessage const*) + 0xc7
2941	KERN: 29 00007fab29aeec30 (+ 736) 00000147a56679d0   <libtracker.so> BPrivate::TTracker::TTracker() + 0x2d0
2942	KERN: 30 00007fab29aeef10 (+ 544) 000000dd28eaf7fd   <_APP_> main + 0x1d
2943	KERN: 31 00007fab29aef130 (+  48) 000000dd28eaf93e   <_APP_> _start + 0x3e
2944	KERN: 32 00007fab29aef160 (+  48) 0000019073f04505   </boot/system/runtime_loader@0x0000019073ef0000> <unknown> + 0x14505
2945	KERN: 33 00007fab29aef190 (+   0) 00007f63729b1260   <commpage> commpage_thread_exit + 0x00

TrackerTaskLoop trying to unload an add-on, this time: 

2946	KERN: kdebug> bt *[1D[1D824stack trace for thread 824 "TrackerTaskLoop"
2947	KERN:     kernel stack: 0xffffffff81b41000 to 0xffffffff81b46000
2948	KERN:       user stack: 0x00007fa630ca3000 to 0x00007fa630ce3000
2949	KERN: frame                       caller             <image>:function + offset
2950	KERN:  0 ffffffff81b45c50 (+ 112) ffffffff800991ca   <kernel_x86_64> reschedule(int) + 0x4fa
2951	KERN:  1 ffffffff81b45cc0 (+  64) ffffffff80088dc6   <kernel_x86_64> thread_block + 0xf6
2952	KERN:  2 ffffffff81b45d00 (+  80) ffffffff80055855   <kernel_x86_64> ConditionVariableEntry::Wait(unsigned int, long) + 0xf5
2953	KERN:  3 ffffffff81b45d50 (+ 256) ffffffff80095eba   <kernel_x86_64> _ZL22user_mutex_wait_lockedPimPKcjlRN8BPrivate10AutoLockerI5mutexNS2_12MutexLockingEEERb.isra.22 + 0x13a
2954	KERN:  4 ffffffff81b45e50 (+  48) ffffffff80096255   <kernel_x86_64> _ZL22user_mutex_lock_lockedPimPKcjlRN8BPrivate10AutoLockerI5mutexNS2_12MutexLockingEEE.isra.23 + 0x65
2955	KERN:  5 ffffffff81b45e80 (+ 160) ffffffff80096724   <kernel_x86_64> _user_mutex_lock + 0xd4
2956	KERN:  6 ffffffff81b45f20 (+  16) ffffffff80152ec8   <kernel_x86_64> x86_64_syscall_entry + 0xfe
2957	KERN: user iframe at 0xffffffff81b45f30 (end = 0xffffffff81b45ff8)
2958	KERN:  rax 0x7                   rbx 0x19074110c28         rcx 0x19073f07834
2959	KERN:  rdx 0x0                   rsi 0x19073f0ac11         rdi 0x19074110c28
2960	KERN:  rbp 0x7fa630ce2880         r8 0x0                    r9 0x0
2961	KERN:  r10 0x0                   r11 0x3246                r12 0x19074110c20
2962	KERN:  r13 0x3                   r14 0xd35                 r15 0x0
2963	KERN:  rip 0x19073f07834         rsp 0x7fa630ce2868     rflags 0x3246
2964	KERN:  vector: 0x63, error code: 0x0
2965	KERN:  7 ffffffff81b45f30 (+140353879001424) 0000019073f07834   </boot/system/runtime_loader@0x0000019073ef0000> <unKERN: known> + 0x17834
2966	KERN:  8 00007fa630ce2880 (+  32) 0000019073f07751   </boot/system/runtime_loader@0x0000019073ef0000> <unknown> + 0x17751
2967	KERN:  9 00007fa630ce28a0 (+  48) 0000019073efbfe3   </boot/system/runtime_loader@0x0000019073ef0000> <unknown> + 0xbfe3
2968	KERN: 10 00007fa630ce28d0 (+  32) 00000150b897e998   <libbe.so> BPrivate::CatalogAddOnInfo::UnloadIfPossible() + 0x38
2969	KERN: 11 00007fa630ce28f0 (+ 304) 00000150b8980911   <libbe.so> BPrivate::MutableLocaleRoster::LoadCatalog const(entry_ref const&, char const*, int) + 0x241
2970	KERN: 12 00007fa630ce2a20 (+  80) 00000150b8976bfe   <libbe.so> BCatalog::SetTo(entry_ref const&, char const*, unsigned int) + 0x5e
2971	KERN: 13 00007fa630ce2a70 (+  64) 00000150b8976e97   <libbe.so> BCatalog::BCatalog(entry_ref const&, char const*, unsigned int) + 0x57
2972	KERN: 14 00007fa630ce2ab0 (+ 288) 00000150b897e6c0   <libbe.so> BLocaleRoster::GetLocalizedFileName(BString&, entry_ref const&, bool) + 0x120
2973	KERN: 15 00007fa630ce2bd0 (+  32) 00000147a562294f   <libtracker.so> BPrivate::Model::CacheLocalizedName() + 0x3f
2974	KERN: 16 00007fa630ce2bf0 (+ 112) 00000147a56233cc   <libtracker.so> BPrivate::Model::OpenNodeCommon(bool) + 0x11c
2975	KERN: 17 00007fa630ce2c60 (+  32) 00000147a562352f   <libtracker.so> BPrivate::Model::OpenNode(bool) + 0x2f
2976	KERN: 18 00007fa630ce2c80 (+  48) 00000147a5623774   <libtracker.so> BPrivate::Model::SetTo(BEntry const*, bool, bool) + 0xa4
2977	KERN: 19 00007fa630ce2cb0 (+ 144) 00000147a5623b9e   <libtracker.so> BPrivate::Model::Model(entry_ref const*, bool, bool, bool) + 0xae
2978	KERN: 20 00007fa630ce2d40 (+ 320) 00000147a562917b   <libtracker.so> BPrivate::NodePreloader::PreloadOne(char const*) + 0xdb
2979	KERN: 21 00007fa630ce2e80 (+ 128) 00000147a5629372   <libtracker.so> BPrivate::NodePreloader::Preload() + 0xd2
2980	KERN: 22 00007fa630ce2f00 (+  32) 00000147a567f595   <libtracker.so> BPrivate::Thread::Run() + 0x15
2981	KERN: 23 00007fa630ce2f20 (+  16) 00000147a567f57a   <libtracker.so> BPrivate::SimpleThread::RunBinder(void*) + 0x0a
2982	KERN: 24 00007fa630ce2f30 (+  32) 00000026550ef2e9   <libroot.so> _thread_do_exit_work (nearest) + 0x79
2983	KERN: 25 00007fa630ce2f50 (+   0) 00007f63729b1260   <commpage> commpage_thread_exit + 0x00

comment:26 by pulkomandy, 4 years ago

Resolution: → fixed
Status: reopened → closed

Fix has been merged. Let us know if it still happens (with a new backtrace for it).

comment:27 by vidrep, 4 years ago

Will do. Thanks for working on the fix.

Note: See TracTickets for help on using tickets.