TotalFinder hangs (or crashes) Finder at startup

TotalFinder
31

I do have the same issue (on 1.6 or 1.6.1)… :confused:

8067da999839a6eeb24cb6db28350948d2fb5e3f378.png1784×2154

32

Hello everyone,

So far I have been unable to reproduce this pesky issue on my machines.

I decided to announce a bounty for whoever provides me with information leading to resolving this bug. Please submit your findings to support@binaryage.com. I would be glad to get some reproducible steps if possible. Or someone to test which beta version first started to express this behaviour.

Current bounty 0.3 BTC

33

For some reason come Macs freeze Finder with version 1.6.1 and some do not. I just uninstalled and reinstalled version 1.6.1 of TotalFinder from a 2009 Mac Mini and a 2010 MacBook Pro. On the former everything works normally, on the latter the Finder process eats up all available CPU. I have no other clue than the graphics adapter (and driver) not being the same. Both Macs are running OS X 10.9.4. And I tried running the MacBook Pro using only discrete graphics card, but Finder still crashes, so the issue is not related to graphics card currently in use.

34

Right after starting TotalFinder on a computer having the Finder using 100% CPU issue, the Console application starts filling up with the following error message:

02/07/14 19:01:32,223 Finder[1282]: FIXME: IOUnserialize has detected a string that is not valid UTF-8, “}W��”.

There are maybe half a dozen of exact same error messages per second, and it goes on and on and on until I kill Finder.

35

Thanks, this is interesting. That is definitely not message logged by TotalFinder’s code.

Googling and I can see there are many mentions on the interwebs.

36

Could you please do some tests on your affected machine?

If the issue is 100% reproducible and caused by TotalFinder’s code, we could try to disable individual TotalFinder pieces one by one to isolate binary causing the trouble. It could be that TotalFinder corrupts some regions of Finder memory and that is causing the problems.

Please try these commands in Terminal.app, Finder restart is needed after each of them, use no to disable their effect again:

First try to disable all plugins:

defaults write com.apple.finder TotalFinderAllPluginsDisabled -bool yes

Then try to disable them one by one:

defaults write com.apple.finder TotalFinder[PLUGIN]Disabled -bool yes

Replace [PLUGIN] with these one by one:

Tabs
ColorfulSidebar
ColumnViewAutoWidth
CopyPathMenu
CutAndPaste
DockProgressBar
FoldersOnTop
Labels
NoDesktopDots
SystemFiles
Visor

37

I’m on the road, but I have working VPN connection to the affected computer. I’ll let you know asap.

38

It seems to crash even after disabling all plugins, so I was stuck at step 1. It opens a “Check for updates automatically?” dialog, but at that point the software is not responding anymore.

39

I have the similar (same?) issue where TotalFinder keeps crashing the Finder. Interestingly, another user account on the same macbook pro works fine. I tried deleting all login items on my current account, but still, TotalFinder does not work. So it must be more low-level than that.

I tried what you suggested, and TotalFinder runs when “Tabs” is disabled. But in this case, TotalFinder is worthless as I cannot use the visor function.
Disabling only the “visor” does not work, however.

Hope this helps.

SJK

40

Now I tried this once more after rebooting the computer. Disabling all plugins keeps Finder working. Then I enabled everything and disabled just the Tabs and it still works after Finder restart. Enabling Tabs and restarting Finder and TotalFinder, Finder starts the CPU 100% loop again. So for me it’s the Tabs that is causing the problem.

1 Like
41

I think this may be related to having shortcuts to servers in the sidebar.

I have rolled back to 10.9.2 & at work I need to access a server through AFP. I dragged both the server & some subfolders on it into my Finder sidebar for quicker access. I did not notice any issues whilst at work but now that I am at home, I’m getting up to half a minute of beach balling. This is not always the case (I think time away from the Finder plays a role here) but if I double-click on a dmg file it is guaranteed to happen.

So I removed the Sidebar entries & the problem has not reared its head for over a day now. I wanted to remove any references to the file server but I am not sure where that information would be kept.

I had a similar problem over a year ago when I foolishly invested in a Transporter & after starting up my Mac, the Finder would lock up for over a minute, several times, whilst giving me error messages about missing servers.

Hope this helps.

1 Like
42

On Macbook Pro with 10.9.3 (and 10.9.4) have the same issue. I have uninstalled TotalFinder, removee all TotalFinder related files from Library, restarted, installed 1.6.1 but no luck. Logs from console:

2014.07.04. 10:47:15,002 TotalFinder[573]: agent v##VERSION## started (TotalFinder)
2014.07.04. 10:47:15,008 TotalFinder[573]: Finder is not running. Launching it... (/System/Library/CoreServices/Finder.app)
2014.07.04. 10:47:15,135 TotalFinder[573]: requesting injection into com.apple.finder[574]
2014.07.04. 10:47:15,687 Finder[574]: TotalFinderInjector v1.6.1 received init event
2014.07.04. 10:47:15,711 Finder[574]: TotalFinderInjector: Installing TotalFinder ...
2014.07.04. 10:47:15,712 Finder[574]: Launching TotalFinderCrashWatcher from '/Library/ScriptingAdditions/TotalFinder.osax/Contents/Resources/TotalFinder.bundle/Contents/Resources/TotalFinderCrashWatcher.app'
2014.07.04. 10:47:15,734 TotalFinder[573]: TotalFinder has been succesfully injected into com.apple.finder[574]
2014.07.04. 10:47:15,750 TotalFinderCrashWatcher[577]: Watching '/Users/werdy/Library/Logs/DiagnosticReports' for new crash reports with prefix 'Finder'
2014.07.04. 10:47:28,362 Console[591]: setPresentationOptions called with NSApplicationPresentationFullScreen when there is no visible fullscreen window; this call will be ignored.
2014.07.04. 10:47:31,131 com.apple.IconServicesAgent[351]: main Failed to composit image for binding VariantBinding [0x455] flags: 0x8 binding: FileInfoBinding [0x1b3] - extension: gif, UTI: com.compuserve.gif, fileType: ????.
2014.07.04. 10:47:31,132 quicklookd[594]: Warning: Cache image returned by the server has size range covering all valid image sizes. Binding: VariantBinding [0x203] flags: 0x8 binding: FileInfoBinding [0x103] - extension: gif, UTI: com.compuserve.gif, fileType: ???? request size:16 scale: 1
2014.07.04. 10:47:31,150 com.apple.IconServicesAgent[351]: main Failed to composit image for binding VariantBinding [0x225] flags: 0x8 binding: FileInfoBinding [0x52d] - extension: sid, UTI: org.sidmusic.sidtune, fileType: ????.
2014.07.04. 10:47:31,151 quicklookd[594]: Warning: Cache image returned by the server has size range covering all valid image sizes. Binding: VariantBinding [0x403] flags: 0x8 binding: FileInfoBinding [0x303] - extension: sid, UTI: org.sidmusic.sidtune, fileType: ???? request size:16 scale: 1
2014.07.04. 10:47:33,277 com.apple.IconServicesAgent[351]: main Failed to composit image for binding VariantBinding [0x1b5] flags: 0x8 binding: FileInfoBinding [0x457] - extension: mp3, UTI: public.mp3, fileType: MP3 .
2014.07.04. 10:47:33,278 quicklookd[594]: Warning: Cache image returned by the server has size range covering all valid image sizes. Binding: VariantBinding [0x603] flags: 0x8 binding: FileInfoBinding [0x503] - extension: mp3, UTI: public.mp3, fileType: MP3  request size:16 scale: 1
2014.07.04. 10:48:26,701 com.apple.mtmd[97]: attempting to thin because of low free space on System (/) by removing 2014-07-04 10:24:22 +0200, estimate 0 bytes to recover, 1 snapshot can be thinned
2014.07.04. 10:48:45,830 com.apple.SecurityServer[15]: Killing auth hosts
2014.07.04. 10:48:45,830 com.apple.SecurityServer[15]: Session 100014 destroyed
2014.07.04. 10:48:58,000 kernel[0]: process Finder[574] thread 7580 caught burning CPU! It used more than 50% CPU (Actual recent usage: 87%) over 180 seconds. thread lifetime cpu usage 90.680991 seconds, (81.988606 user, 8.692385 system) ledger info: balance: 90001463504 credit: 90001463504 debit: 0 limit: 90000000000 (50%) period: 180000000000 time since last refill (ns): 103050176172 
2014.07.04. 10:48:58,087 ReportCrash[607]: Invoking spindump for pid=574 thread=7580 percent_cpu=87 duration=104 because of excessive cpu utilization
2014.07.04. 10:49:01,044 spindump[608]: Saved cpu_resource.spin report for Finder version 10.9.4 (10.9.4) to /Library/Logs/DiagnosticReports/Finder_2014-07-04-104901_Werdys-Macbook-Pro.cpu_resource.spin
2014.07.04. 10:49:22,304 WindowServer[143]: disable_update_timeout: UI updates were forcibly disabled by application "Finder" for over 1.00 seconds. Server has re-enabled them.

I hope this help to resolve this bug.

1 Like
43

Disabling only Tabs plugin has solved the problem.

1 Like
44

Ok, the problem is somewhere in the Tabs plugin. Thanks for narrowing it down.

@werdy It would be great if you could send me the file /Library/Logs/DiagnosticReports/Finder_2014-07-04-104901_Werdys-Macbook-Pro.cpu_resource.spin to support@binaryage.com. It could tell where exactly is Tabs code spending the CPU time.

45

@darwin Sent.

46

In that report there were two active plugins in memory, Tabs and ColumnViewAutoWidth. Could you do another test, just with Tabs enabled?

Also I have one additional tweak to try.

Enable only Tabs plugin. Make sure the issue is reproducible. Then set this tweak and restart Finder + TotalFinder again.

defaults write com.apple.finder TotalFinderDisableCrashInfo -bool yes
47

Only Tabs enabled it hanged:

    2014.07.04. 13:03:36,099 TotalFinder[573]: requesting injection into com.apple.finder[2688]
    2014.07.04. 13:03:36,416 Finder[2688]: TotalFinderInjector v1.6.1 received init event
    2014.07.04. 13:03:36,429 Finder[2688]: TotalFinderInjector: Installing TotalFinder ...
    2014.07.04. 13:03:36,430 Finder[2688]: Launching TotalFinderCrashWatcher from '/Library/ScriptingAdditions/TotalFinder.osax/Contents/Resources/TotalFinder.bundle/Contents/Resources/TotalFinderCrashWatcher.app'
    2014.07.04. 13:03:36,437 TotalFinder[573]: TotalFinder has been succesfully injected into com.apple.finder[2688]
    2014.07.04. 13:03:38,491 Finder[2688]: Skipping loading plugin ColorfulSidebar because it is disabled by plist switch TotalFinderColorfulSidebarDisabled.
    2014.07.04. 13:03:38,492 Finder[2688]: Skipping loading plugin ColumnViewAutoWidth because it is disabled by plist switch TotalFinderColumnViewAutoWidthDisabled.
    2014.07.04. 13:03:38,492 Finder[2688]: Skipping loading plugin CopyPathMenu because it is disabled by plist switch TotalFinderCopyPathMenuDisabled.
    2014.07.04. 13:03:38,492 Finder[2688]: Skipping loading plugin CutAndPaste because it is disabled by plist switch TotalFinderCutAndPasteDisabled.
    2014.07.04. 13:03:38,492 Finder[2688]: Skipping loading plugin DockProgressBar because it is disabled by plist switch TotalFinderDockProgressBarDisabled.
    2014.07.04. 13:03:38,492 Finder[2688]: Skipping loading plugin FoldersOnTop because it is disabled by plist switch TotalFinderFoldersOnTopDisabled.
    2014.07.04. 13:03:38,493 Finder[2688]: Skipping loading plugin Labels because it is disabled by plist switch TotalFinderLabelsDisabled.
    2014.07.04. 13:03:38,493 Finder[2688]: Skipping loading plugin NoDesktopDots because it is disabled by plist switch TotalFinderNoDesktopDotsDisabled.
    2014.07.04. 13:03:38,493 Finder[2688]: Skipping loading plugin SystemFiles because it is disabled by plist switch TotalFinderSystemFilesDisabled.
    2014.07.04. 13:03:38,494 Finder[2688]: Skipping loading plugin Visor because it is disabled by plist switch TotalFinderVisorDisabled.
    2014.07.04. 13:05:16,000 kernel[0]: process Finder[2688] thread 78498 caught burning CPU! It used more than 50% CPU (Actual recent usage: 80%) over 180 seconds. thread lifetime cpu usage 90.613343 seconds, (81.521489 user, 9.091854 system) ledger info: balance: 90001594441 credit: 90001594441 debit: 0 limit: 90000000000 (50%) period: 180000000000 time since last refill (ns): 112121656741 
    2014.07.04. 13:05:16,985 ReportCrash[2703]: Invoking spindump for pid=2688 thread=78498 percent_cpu=80 duration=113 because of excessive cpu utilization
    2014.07.04. 13:05:19,794 spindump[2704]: Saved cpu_resource.spin report for Finder version 10.9.4 (10.9.4) to /Library/Logs/DiagnosticReports/Finder_2014-07-04-130519_Werdys-Macbook-Pro.cpu_resource.spin

With crash info disabled hanged again (without crash info):

2014.07.04. 13:08:07,390 TotalFinder[573]: requesting injection into com.apple.finder[2710]
2014.07.04. 13:08:07,688 Finder[2710]: TotalFinderInjector v1.6.1 received init event
2014.07.04. 13:08:07,702 Finder[2710]: TotalFinderInjector: Installing TotalFinder ...
2014.07.04. 13:08:07,702 Finder[2710]: Disabled advanced crash info (defaults delete com.apple.finder TotalFinderDisableCrashInfo)
2014.07.04. 13:08:07,703 Finder[2710]: Launching TotalFinderCrashWatcher from '/Library/ScriptingAdditions/TotalFinder.osax/Contents/Resources/TotalFinder.bundle/Contents/Resources/TotalFinderCrashWatcher.app'
2014.07.04. 13:08:07,711 TotalFinder[573]: TotalFinder has been succesfully injected into com.apple.finder[2710]
2014.07.04. 13:08:09,763 Finder[2710]: Skipping loading plugin ColorfulSidebar because it is disabled by plist switch TotalFinderColorfulSidebarDisabled.
2014.07.04. 13:08:09,763 Finder[2710]: Skipping loading plugin ColumnViewAutoWidth because it is disabled by plist switch TotalFinderColumnViewAutoWidthDisabled.
2014.07.04. 13:08:09,763 Finder[2710]: Skipping loading plugin CopyPathMenu because it is disabled by plist switch TotalFinderCopyPathMenuDisabled.
2014.07.04. 13:08:09,763 Finder[2710]: Skipping loading plugin CutAndPaste because it is disabled by plist switch TotalFinderCutAndPasteDisabled.
2014.07.04. 13:08:09,764 Finder[2710]: Skipping loading plugin DockProgressBar because it is disabled by plist switch TotalFinderDockProgressBarDisabled.
2014.07.04. 13:08:09,764 Finder[2710]: Skipping loading plugin FoldersOnTop because it is disabled by plist switch TotalFinderFoldersOnTopDisabled.
2014.07.04. 13:08:09,764 Finder[2710]: Skipping loading plugin Labels because it is disabled by plist switch TotalFinderLabelsDisabled.
2014.07.04. 13:08:09,764 Finder[2710]: Skipping loading plugin NoDesktopDots because it is disabled by plist switch TotalFinderNoDesktopDotsDisabled.
2014.07.04. 13:08:09,764 Finder[2710]: Skipping loading plugin SystemFiles because it is disabled by plist switch TotalFinderSystemFilesDisabled.
2014.07.04. 13:08:09,765 Finder[2710]: Skipping loading plugin Visor because it is disabled by plist switch TotalFinderVisorDisabled.

I will send you cpu_resource.spin file in email for the first case.

48

Thank you very much.

Please test another tweak (with Tabs plugin enabled):

defaults write com.apple.finder TotalFinderDontRestoreTabsState -bool yes
49

And I would need one extra sample. Files you provided are ‘spin dumps’. I would like to see Finder.app process sample.

Please

  1. open Activity Monitor.app
  2. filter process list with “Finder” keyword
  3. sample Finder.app (not TotalFinder.app)

Hit the sample button when Finder is eating 100% only with Tabs plugin loaded. Send the sample file to my email. Thanks.

50

@darwin Sorry, but I can not send you spin dumps, as the bug is gone. I disabled tab state recovering as you wrote, run activity monitor and realized that TotalFinder and TotalFinderCrashWatcher were running (while icon on dock displayed only Finder). After I closed these processes and restart Finder TotalFinder worked as it should be. I reenabled all the plugins and still working. Did tab state restoring cause the hangs?