finder crashing and restarting when dragging files to it

[The-Pro]

Hi everybody,

Its been going on since I first installed mavericks. I decided to deal with it but now I would like to now what could be the cause.

When I drag files to a folder on my desktop or anywhere else in my user account then it crashes and restarts. Usually after about the 5th image I am dragging from google to a folder the finder restarts.
Sometimes it doesnt restart but instead when I drag the image to my folder the finder doesn't recognise that my mouse is over it. At that point I cant drag anything to a folder. I need to restart the finder manually for it to start working again.
On other occasions I drag the file to the folder, the blue border appears, and I "drop" the file. But instead of placing the file in the new location it snaps back to where it came from. I then need to restart the finder to make it work.

This is awfully annoying
It never happened on my 2010 15" MBP and it doesnt happen on any other of my many macs running mavericks.
But on this 2012 15" 2.6 i7 MBP it does. The MBP has a nearly 3 year old samsung 470 series 256GB SSD, which doesnt seem to have any issues. Disk Utility is saying all is good, and 10 GB of RAM (1x 2GB original RAM and 1 x 8GB samsung 8GB) I've never had 2 different sized memory modules in a computer before, could this be the problem?

One thing that I noticed in the console is that every 5 seconds this log is entered:
kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000

there are currently 4000 console messages since the 1st of january, and pretty much 99% of these are the log from above. Ive never seen this happen before.

Hope someone knows the culprit
thanks


Here are the consolse logs when the third scenario starts to occur:

02/01/14 21:17:42,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
02/01/14 21:17:43,674 Finder[971]: Can not nest column dragging sessions
02/01/14 21:17:43,676 Finder[971]: (
0 CoreFoundation 0x00007fff99e1c41c __exceptionPreprocess + 172
1 libobjc.A.dylib 0x00007fff8d935e75 objc_exception_throw + 43
2 AppKit 0x00007fff8fa129dd -[NSBrowser _setDropTargetColumn:targetRow:targetDropOperation:dragOperation:] + 0
3 AppKit 0x00007fff8fa1b063 -[NSBrowserTableView draggingEntered:] + 103
4 Finder 0x00000001025c4c4a Finder + 3091530
5 AppKit 0x00007fff8f94a7a2 NSCoreDragTrackingProc + 1822
6 HIServices 0x00007fff94efa158 DoTrackingMessage + 370
7 HIServices 0x00007fff94efcfe3 CoreDragMessageHandler + 543
8 CoreFoundation 0x00007fff99dc7f78 __CFMessagePortPerform + 760
9 CoreFoundation 0x00007fff99d4db69 __CFRUNLOOP_IS_CALLING_OUT_TO_A_SOURCE1_PERFORM_FUNCTION__ + 41
10 CoreFoundation 0x00007fff99d4dade __CFRunLoopDoSource1 + 478
11 CoreFoundation 0x00007fff99d3ebd6 __CFRunLoopRun + 1830
12 CoreFoundation 0x00007fff99d3e275 CFRunLoopRunSpecific + 309
13 HIToolbox 0x00007fff9415af0d RunCurrentEventLoopInMode + 226
14 HIToolbox 0x00007fff9415acb7 ReceiveNextEventCommon + 479
15 HIToolbox 0x00007fff9415aabc _BlockUntilNextEventMatchingListInModeWithFilter + 65
16 AppKit 0x00007fff8f63e28e _DPSNextEvent + 1434
17 AppKit 0x00007fff8f63d8db -[NSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] + 122
18 AppKit 0x00007fff8f6319cc -[NSApplication run] + 553
19 AppKit 0x00007fff8f61c803 NSApplicationMain + 940
20 Finder 0x00000001022d84dc Finder + 25820
21 libdyld.dylib 0x00007fff8f6055fd start + 1
22 ??? 0x0000000000000001 0x0 + 1
)
02/01/14 21:17:47,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
02/01/14 21:17:47,487 Finder[971]: Error in CoreDragGetMouseLocation: -1850
02/01/14 21:17:49,152 Finder[971]: Error in CoreDragGetMouseLocation: -1850
02/01/14 21:17:51,593 sandboxd[228]: ([978]) CVMCompiler(978) deny mach-lookup com.apple.distributed_notifications@Uv3

 

[wrldwzrd89]

This here line is interesting:

02/01/14 21:17:43,674 Finder[971]: Can not nest column dragging sessions

That tells me that Finder thinks you're trying to perform multiple drag and drop operations at the same time, which makes no sense. However, digging into the cause of that is going to require analysis of the crash log.

 

[The-Pro]

This here line is interesting:

02/01/14 21:17:43,674 Finder[971]: Can not nest column dragging sessions

That tells me that Finder thinks you're trying to perform multiple drag and drop operations at the same time, which makes no sense. However, digging into the cause of that is going to require analysis of the crash log.

yeah that makes no sense.

replicated the first scenario. Here is the console log

03/01/14 11:04:57,747 Safari[639]: *** CFMessagePort: dropping corrupt reply Mach message (0b100101)
03/01/14 11:04:57,756 com.apple.launchd.peruser.502[626]: (com.apple.Finder[979]) Job appears to have crashed: Segmentation fault: 11
03/01/14 11:04:57,946 xpcproxy[1510]: assertion failed: 13B42: xpcproxy + 3438 [EE7817B0-1FA1-3603-B88A-BD5E595DA86F]: 0x2
03/01/14 11:04:58,355 ReportCrash[1504]: Saved crash report for Finder[979] version 10.9.1 (10.9.1) to /Users/Username/Library/Logs/DiagnosticReports/Finder_2014-01-03-110458_MacBook-Pro.crash
03/01/14 11:04:59,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
03/01/14 11:05:03,344 com.apple.iCloudHelper[1510]: AOSKit ERROR: ((null)) Cannot refresh account bag, missing acct info (user=95580294, hasPassword=0)

Ive I couldnt attach the .crash file so I copied the log into text edit to create a txt file

Something else I've been noticing. I have extremely long wakeup times. 30-50 seconds it take to type in my password when opening the MBP.
And I have massive memory leaks (i think thats what its called). Right now I have 4GB of "inactive" memory. This happens randomly. I could just be
browsing or sorting files and I get massive inactive memory amounts. Sometimes it just disappears again. Activity monitor doesnt show whats using this memory.

 

[wrldwzrd89]

I've looked at your crash log. I see no obvious culprits, but what I do see are signs that Finder got VERY confused trying to figure out how to handle the drag and drop request - it's almost as if it thinks you're trying to drop something into two columns in Column View at the same time. Try the drag & drop operation in some other view, and see if it crashes there, too.

As for your other questions:
The long wakeup time is not normal. Do you have external drives connected to your MBP? For whatever reason, when a Mac wakes from sleep, it waits for all the external drives to spin up first. The memory-related thing you're seeing is perfectly normal: Mavericks manages RAM in a very different way from previous versions. This is also why you don't see some RAM stats in Activity Monitor that were there in previous versions; with the advent of Compressed Memory, they don't apply.

 

[Weaselboy]

One thing that I noticed in the console is that every 5 seconds this log is entered:
kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000

Do you happen to have iStat menus, iStat pro, or SMCFanControl... anything like that installed? Those apps can cause the log entries you are seeing.

 

[The-Pro]

I've looked at your crash log. I see no obvious culprits, but what I do see are signs that Finder got VERY confused trying to figure out how to handle the drag and drop request - it's almost as if it thinks you're trying to drop something into two columns in Column View at the same time. Try the drag & drop operation in some other view, and see if it crashes there, too.

As for your other questions:
The long wakeup time is not normal. Do you have external drives connected to your MBP? For whatever reason, when a Mac wakes from sleep, it waits for all the external drives to spin up first. The memory-related thing you're seeing is perfectly normal: Mavericks manages RAM in a very different way from previous versions. This is also why you don't see some RAM stats in Activity Monitor that were there in previous versions; with the advent of Compressed Memory, they don't apply.

tried dragging and dropping in list view and it did not crash. Went back to column view and it did. Column view seems to be the problem then. Never heard of this issue before, is it a mavericks bug or something special just with me?

I dont have external drives connected no. couple things i noticed though are:
When the sleep indicator LED is pulsing (sleep) then the wake up, after opening the screen is near instant, about 1 sec. However:
most of the time, i close the screen and the light pulses for some time and then no longer illuminates at all. When I open the screen, the wake up usually takes 15-20 seconds.
Sometimes when i open the screen i hear my optical drive make a noise, the same noise it makes when starting up. Then the wakeup, to get to my log in screen takes about 30-50 seconds. much slower then booting up.

Also the log in screen in all three scenarios is unresponsive for a couple seconds. So i need to wait a further 3-5 seconds before i can type in my password.

I have attached the wakeup logs for the third scenario where i hear the optical drive make a noise. Only took 20 seconds this time.

here is the logs from waking up. Light was not pulsing before and I heard no optical drive noise. this wakeup took only about 10 seconds (14 before I could type my password), but still too long.

04/01/14 15:32:49,000 kernel[0]: vm_compressor_flush - starting
04/01/14 15:32:49,000 kernel[0]: vm_compressor_flush completed - took 10286 msecs
04/01/14 15:32:49,000 kernel[0]: hibernate_flush_memory: buffer_cache_gc freed up 10182 wired pages
04/01/14 15:32:49,000 kernel[0]: hibernate_alloc_pages act 204823, inact 38138, anon 3762, throt 0, spec 85735, wire 239600, wireinit 258869
04/01/14 15:32:49,000 kernel[0]: hibernate_setup(0) took 11738 ms
04/01/14 15:32:49,000 kernel[0]: Bluetooth -- LE is supported - Disable LE meta event
04/01/14 15:32:49,000 kernel[0]: **** [IOBluetoothHostControllerUSBTransport][SuspendDevice] -- Suspend -- suspendDeviceCallResult = 0x0000 (kIOReturnSuccess) -- 0x4c00 ****
04/01/14 15:08:51,000 kernel[0]: hibernate_page_list_setall(preflight 0) start 0xffffff81a9d7c000, 0xffffff81afa22000
04/01/14 15:32:49,000 kernel[0]: hibernate_teardown started
04/01/14 15:32:49,000 kernel[0]: hibernate_teardown: wired_pages 240118, free_pages 2291502, active_pages 70930, inactive_pages 1261, speculative_pages 204, cleaned_pages 0, compressor_pages 24
04/01/14 15:32:49,000 kernel[0]: hibernate_teardown_pmap_structs done: last_valid_compact_indx 193867
04/01/14 15:32:49,000 kernel[0]: hibernate_teardown: mark_as_unneeded_pages 8192, 37820, 22528
04/01/14 15:32:49,000 kernel[0]: hibernate_teardown completed - discarded 68540
04/01/14 15:32:49,000 kernel[0]: hibernate_page_list_setall time: 597 ms
04/01/14 15:32:49,000 kernel[0]: pages 484819, wire 156099, act 70930, inact 1261, cleaned 0 spec 204, zf 0, throt 0, compr 24, xpmapped 72395
04/01/14 15:32:49,000 kernel[0]: did discard act 64820 inact 36621 purgeable 69329 spec 85531 cleaned 0
04/01/14 15:32:49,000 kernel[0]: hibernate_page_list_setall found pageCount 228518
04/01/14 15:32:49,000 kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
04/01/14 15:32:49,000 kernel[0]: IOHibernatePollerOpen(0)
04/01/14 15:32:49,000 kernel[0]: encryptStart 14040
04/01/14 15:32:49,000 kernel[0]: bitmap_size 0x4f8dc, previewSize 0xac8610, writing 225426 pages @ 0xb2bf2c
04/01/14 15:32:49,000 kernel[0]: hibernate_rebuild started
04/01/14 15:32:49,000 kernel[0]: hibernate_rebuild_pmap_structs done: last_valid_compact_indx 193867
04/01/14 15:32:49,000 kernel[0]: hibernate_rebuild completed - took 64 msecs
04/01/14 15:32:49,000 kernel[0]: booter start at 7126 ms smc 0 ms, [13, 0, 0] total 2827 ms, dsply 818, 33 ms, tramp 481 ms
04/01/14 15:32:49,000 kernel[0]: hibernate_machine_init: state 2, image pages 155896, sum was a95ffee, imageSize 0x1c7a9000, image1Size 0xc02b000, conflictCount 1294, nextFree 439
04/01/14 15:32:49,000 kernel[0]: hibernate_newruntime_map time: 0 ms, IOHibernatePollerOpen(), ml_get_interrupts_enabled 0
04/01/14 15:32:49,000 kernel[0]: IOHibernatePollerOpen(0) 20 ms
04/01/14 15:32:49,000 kernel[0]: hibernate_machine_init reading
04/01/14 15:32:49,000 kernel[0]: PMStats: Hibernate read took 1031 ms
04/01/14 15:32:49,000 kernel[0]: hibernate_machine_init pagesDone 228315 sum2 465f153e, time: 1031 ms, disk(0x20000) 260 Mb/s, comp bytes: 106541056 time: 105 ms 965 Mb/s, crypt bytes: 276291584 time: 117 ms 2249 Mb/s
04/01/14 15:32:49,000 kernel[0]: Wake reason: EC.LidOpen (User)
04/01/14 15:32:49,000 kernel[0]: AirPort_Brcm43xx:owerChange: System Wake - Full Wake/ Dark Wake / Maintenance wake
04/01/14 15:32:49,000 kernel[0]: Previous Sleep Cause: 5
04/01/14 15:32:49,000 kernel[0]: Previous Shutdown Cause: 5
04/01/14 15:32:49,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
04/01/14 15:32:50,000 kernel[0]: IOBluetoothUSBDFU:robe
04/01/14 15:32:50,000 kernel[0]: IOBluetoothUSBDFU:robe ProductID - 0x821D FirmwareVersion - 0x0131
04/01/14 15:32:50,000 kernel[0]: **** [IOBluetoothHostControllerUSBTransport][start] -- completed -- result = TRUE -- 0x9400 ****
04/01/14 15:32:50,000 kernel[0]: **** [BroadcomBluetoothHostControllerUSBTransport][start] -- Completed -- 0x9400 ****
04/01/14 15:32:50,000 kernel[0]: [IOBluetoothHCIController][staticBluetoothHCIControllerTransportShowsUp] -- Received Bluetooth Controller register service notification -- 0x9400
04/01/14 15:32:50,000 kernel[0]: [IOBluetoothHCIController::setConfigState] calling registerService
04/01/14 15:32:50,000 kernel[0]: **** [IOBluetoothHCIController][protectedBluetoothHCIControllerTransportShowsUp] -- Connected to the transport successfully -- 0xd080 -- 0x0800 -- 0x9400 ****
04/01/14 15:32:50,371 WindowServer[197]: CGXDisplayDidWakeNotification [30605855688655]: posting kCGSDisplayDidWake
04/01/14 15:32:50,404 WindowServer[197]: handle_will_sleep_auth_and_shield_windows: Reordering authw 0x7fc9b963d380(2000) (lock state: 3)
04/01/14 15:32:50,404 WindowServer[197]: handle_will_sleep_auth_and_shield_windows: err 0x0
04/01/14 15:32:51,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
04/01/14 15:32:56,529 Calendar[645]: [com.apple.calendar.ui.log] [An error exists on principal: [Gmail]]
04/01/14 15:32:56,532 Calendar[645]: [com.apple.calendar.ui.log] [An error exists on principal: [iCloud]]
04/01/14 15:32:56,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
04/01/14 15:33:01,000 kernel[0]: SMC::smcReadKeyAction ERROR TC0D kSMCBadArgumentError(0x89) fKeyHashTable=0x0xffffff8043c0b000
04/01/14 15:33:01,744 loginwindow[163]: CoreAnimation: warning, deleted thread with uncommitted CATransaction; set CA_DEBUG_TRANSACTIONS=1 in environment to log backtraces.



thank you so much for your time

Do you happen to have iStat menus, iStat pro, or SMCFanControl... anything like that installed? Those apps can cause the log entries you are seeing.

I have istat menues installed. So thats solved.
thanks