Final Cut X.1.1 Slow To Open On nMP

[Switchfoot]

Asked this over in the video production subforum, but thought I'd throw it out here.

I've noticed FCPX has slowed down it's opening sequence significantly on my nMP (25 seconds). I'm on a dual monitor setup, 8 core 32gb, and my current project is maybe 20-30 seconds long.

Any ideas?


EDIT: OK, now I'm just confused. I created a new library and hid all the others and moved them out of the movies folder. Still had the hang. However, out of desperation I pulled my SD card from my camera and plugged it back in and presto- FCPX starts up in 2 seconds now. Mind you, I never imported from the card- I always copy over any media to my HD, then import to FCPX.

EDIT 2: One more piece to the puzzle before I quit for the night. The SD trick I mentioned earlier only works for the first few minutes- then FCPX returns to the 25 seconds startup unless I eject and reinsert the SD card. I've deleted all my libraries and events, I've deleted the 3 pref files, nothing. I've created a test account, and FCPX runs lightning fast on it. Guess I'll reinstall while I sleep, but I'd love if anyone had any other advice!

 

[Anim]

Have you checked "Console" while loading up FCX? It might show some errors/timeouts to help you diagnose.

 

[Switchfoot]

Have you checked "Console" while loading up FCX? It might show some errors/timeouts to help you diagnose.

Thanks for the reply! I've reinstalled FCPX, but the problem persists. I've watched Console while launching, but nothing appears for Final Cut, even while the beach ball is spinning.

 

[Anim]

Hmm

Have you run a repair disk permission?

Any mounted drives in finder that you don't need or can unmount while testing?

Could a recent file on a volume that is inaccessible causing a pause?

Finally you could select FCPX as a "Target" and run it through Instruments->OSX->File Activity scheme to see what files it is reading and writing during the big pause. Instruments is part of xCode.

 

[wheelhot]

When you get your nMP did you use the OSX transfer from old to new computer feature?

 

[koban4max]

Asked this over in the video production subforum, but thought I'd throw it out here.

I've noticed FCPX has slowed down it's opening sequence significantly on my nMP (25 seconds). I'm on a dual monitor setup, 8 core 32gb, and my current project is maybe 20-30 seconds long.

Any ideas?


EDIT: OK, now I'm just confused. I created a new library and hid all the others and moved them out of the movies folder. Still had the hang. However, out of desperation I pulled my SD card from my camera and plugged it back in and presto- FCPX starts up in 2 seconds now. Mind you, I never imported from the card- I always copy over any media to my HD, then import to FCPX.

EDIT 2: One more piece to the puzzle before I quit for the night. The SD trick I mentioned earlier only works for the first few minutes- then FCPX returns to the 25 seconds startup unless I eject and reinsert the SD card. I've deleted all my libraries and events, I've deleted the 3 pref files, nothing. I've created a test account, and FCPX runs lightning fast on it. Guess I'll reinstall while I sleep, but I'd love if anyone had any other advice!

which mp you got..?

 

[Switchfoot]

When you get your nMP did you use the OSX transfer from old to new computer feature?

I did not- all fresh.

which mp you got..?

Late 2013, 8 Core, 32GB RAM, D700.

----------

Hmm

Have you run a repair disk permission?

Any mounted drives in finder that you don't need or can unmount while testing?

Could a recent file on a volume that is inaccessible causing a pause?

Finally you could select FCPX as a "Target" and run it through Instruments->OSX->File Activity scheme to see what files it is reading and writing during the big pause. Instruments is part of xCode.

I'll get started on those and report back- thanks for the advice!

 

[Switchfoot]

Hmm

Have you run a repair disk permission?

Any mounted drives in finder that you don't need or can unmount while testing?

Could a recent file on a volume that is inaccessible causing a pause?

Finally you could select FCPX as a "Target" and run it through Instruments->OSX->File Activity scheme to see what files it is reading and writing during the big pause. Instruments is part of xCode.

Couple of updates (still downloading XCode):

Console shows these after FCPX finally launches:
3/13/14 12:04:48.952 AM WindowServer[3709]: disable_update_timeout: UI updates were forcibly disabled by application "Final Cut Pro" for over 1.00 seconds. Server has re-enabled them.

3/13/14 12:04:49.208 AM WindowServer[3709]: common_reenable_update: UI updates were finally reenabled by application "Final Cut Pro" after 1.26 seconds (server forcibly re-enabled them after 1.00 seconds)


and, if I force quit FCPX before it starts actually launching (when it just shows this but no words to show it's loading:

the error report includes this:

Date/Time: 2014-03-13 00:09:04 -0500
OS Version: 10.9.2 (Build 13C64)
Architecture: x86_64
Report Version: 18

Command: Final Cut Pro
Path: /Applications/Final Cut Pro.app/Contents/MacOS/Final Cut Pro
Version: 10.1.1 (240682)
Build Version: 1
Project Name: ProEditor
Source Version: 24068002033000000
App Item ID: 424389933
App External ID: 299842652
Parent: launchd [170]

PID: 4522
Event: hang
Duration: 0.99s (process was unresponsive for 11 seconds before sampling)
Steps: 10 (100ms sampling interval)

Hardware model: MacPro6,1
Active cpus: 16
Fan speed: 790 rpm

Free pages: 6324111 pages (-1998)
Pageins: 0 pages
Pageouts: 0 pages
Swapins: 0 pages
Swapouts: 0 pages


Process: Final Cut Pro [4522]
Path: /Applications/Final Cut Pro.app/Contents/MacOS/Final Cut Pro
Architecture: x86_64
Parent: launchd [170]
UID: 501
Task size: 26606 pages
Note: Unresponsive for 11 seconds before sampling

Thread 0x47b42 DispatchQueue 1 priority 47
10 start + 1 (libdyld.dylib) [0x7fff827ef5fd]
10 NSProApplicationMain + 333 (ProKit) [0x101730f6a]
10 -[NSApplication run] + 553 (AppKit) [0x7fff81b7db2c]
10 -[NSApplication nextEventMatchingMask:untilDate:inMode:dequeue:] + 122 (AppKit) [0x7fff81b89a2b]
10 _DPSNextEvent + 1026 (AppKit) [0x7fff81b8a246]
10 AEProcessAppleEvent + 56 (HIToolbox) [0x7fff85241161]
10 aeProcessAppleEvent + 315 (AE) [0x7fff83dc3b36]
10 dispatchEventAndSendReply(AEDesc const*, AEDesc*) + 31 (AE) [0x7fff83dc3c32]
10 aeDispatchAppleEvent(AEDesc const*, AEDesc*, unsigned int, unsigned char*) + 381 (AE) [0x7fff83dc3e1f]
10 _NSAppleEventManagerGenericHandler + 106 (Foundation) [0x7fff81516d7d]
10 -[NSAppleEventManager dispatchRawAppleEvent:withRawReply:handlerRefCon:] + 294 (Foundation) [0x7fff81516f0a]
10 -[NSApplication(NSAppleEventHandling) _handleCoreEvent:withReplyEvent:] + 242 (AppKit) [0x7fff81b8e36b]
10 -[NSApplication(NSAppleEventHandling) _handleAEOpenEvent:] + 570 (AppKit) [0x7fff81b8e916]
10 -[NSApplication _sendFinishLaunchingNotification] + 195 (AppKit) [0x7fff81b91a2c]
10 -[NSApplication _postDidFinishNotification] + 289 (AppKit) [0x7fff81b91cf9]
10 -[NSNotificationCenter postNotificationNamebject:userInfo:] + 68 (Foundation) [0x7fff814f87ba]
10 _CFXNotificationPost + 2893 (CoreFoundation) [0x7fff8ccffa6d]
10 __CFNOTIFICATIONCENTER_IS_CALLING_OUT_TO_AN_OBSERVER__ + 12 (CoreFoundation) [0x7fff8ce0be0c]
10 -[PEAppController applicationDidFinishLaunching:] + 1055 (Final Cut Pro) [0x10142364f]
10 -[PEAppController _migratePreGodzillaColorPresets] + 40 (Final Cut Pro) [0x101423218]
10 +[FFColorLooksList migrateColorPresets:] + 246 (Flexo) [0x1022c8e26]
10 +[FFStorageManager moviesURL] + 69 (Flexo) [0x101ddcd15]
10 +[FFStorageManager sharedInstance] + 52 (Flexo) [0x101ddb0d4]
10 -[FFStorageManager init] + 221 (Flexo) [0x101ddb21d]
10 -[FFStorageManager refreshAvailableVolumesAlwaysWarnForPath:] + 50 (Flexo) [0x101ddf792]
10 -[FFStorageManager newAvailableLocationsWithLegacyBehavior:alwaysWarnPath:] + 1907 (Flexo) [0x101ddf643]
10 -[FFStorageManager locationFromVolumePath:volumeRefNum:diskUUID:networkPath:alwaysWarnPath:] + 138 (Flexo) [0x101ddeeaa]
10 -[FFStorageLocation isTimeMachineVolume] + 107 (Flexo) [0x101dd9aab]
10 -[NSFileManager fileExistsAtPath:isDirectory:] + 126 (Foundation) [0x7fff8150afdf]
10 lstat$INODE64 + 10 (libsystem_kernel.dylib) [0x7fff80d9f70a]
*10 hndl_unix_scall64 + 22 (mach_kernel) [0xffffff80002f3b26]
*10 unix_syscall64 + 499 (mach_kernel) [0xffffff800063e363]
*10 lstat64 + 156 (mach_kernel) [0xffffff80003e936c]



it goes on and on, but I can post the rest if anyone thinks it might help.

Again thanks for looking! It's driving me crazy.

 

[Switchfoot]

Ok! Got back the Instruments Results, and I think it may have narrowed it down- I just have no clue what to do next.

Here are the important images:

This one shows the lull between startup and when the app actually starts loading:

Ok, so then I went through and found the last things it was doing right before the lull for each Instrument:

1)File Activity:
18844 (sorry, it's the one before the highlighted row in the picture)

2)Reads/Writes:
1383 occurs right before lull

3)File Attributes:
2926 before lull

4)Directory I/O:

1 and 2 seem to happen about 3 seconds into the lull

Again, I don't know much about this, but from what I can find on NETFSPlugins and SMB, it looks like FCPX might be looking for file shared drives? I've connected to my laptop and a couple of other network computers through the Finder, but there are no files currently in FCPX from any of those machines.

Any light you can shed on the results would be greatly appreciated!

----------

Looks like I may have been right- disabling WiFi (my only network connection) brought FCPX back to it's full speed. Opens in under a second.

Turn wifi back on and we're back to 25 second loads.

Any way to forget all SMB connections? Or at least make FCPX forget them?

What a strange problem.... hope it helps anyone else looking though!

----------

!SOLVED!

Had to go under Finder- Go- "Connect To Server" then "Clear Recent Servers" from the little clock menu. FCPX is back to quick startups.

Thanks for the great Instruments suggestion Anim! I'll submit a bug report to Apple-- really wish FCP would be more informative on background processes like this.

Last Edit: Also worth trying- Go under Finder, and (if applicable to you) right click on Remote Disc and select "Eject Remote Disc"

 

[Chippy99]

Did this definitely fix the problem for you? I tried your fix and it has not worked for me, sadly.

I have EXACTLY the same problem and was optimistic your fix would work, and I can't figure out why it does not.

 

[Anim]

You did some great narrowing down there.

Certainly a detailed report for Apple to get started on and glad you solved it.

I noticed a "backupd" before the pause so something related to backups. Look and see if you can disable any automated backups within FCP preferences as it could be trying to load your last session in, that session could be corrupt or saved on an unmounted volume.

Is time machine running ok? where is /net?

You don't have Google Drive installed do you? I have read reports that can cause freezing and timeouts in Mavericks.

Chippy99, check out this list of fixes:
http://fcpx.tv/troubleshooting.html

Anim

 

[Chippy99]

Thanks Anim for the helpful suggestions. I have tried pretty much all of the suggestions in the link you provided, including a complete reinstall.

I strongly suspect this is an OSX (rather than FCP) problem at the root of this. Because I have exactly the same problem with iMovie.

If I disconnect my Ethernet connection, it works perfectly 100% every time. Bizarrely, I can switch on Wifi and connect to the internet via Wifi, and it still works perfectly 100% of the time. Then when I switch back to using Ethernet, the problem comes back again immediately.

I am stumped.

Ethernet connected:

Ethernet disconnected:

 

[Anim]

Thanks Anim for the helpful suggestions. I have tried pretty much all of the suggestions in the link you provided, including a complete reinstall.

I strongly suspect this is an OSX (rather than FCP) problem at the root of this. Because I have exactly the same problem with iMovie.

If I disconnect my Ethernet connection, it works perfectly 100% every time. Bizarrely, I can switch on Wifi and connect to the internet via Wifi, and it still works perfectly 100% of the time. Then when I switch back to using Ethernet, the problem comes back again immediately.

I am stumped.

Ethernet connected:
Image

Ethernet disconnected:
Image

Yeah, it looks like it is getting stuck accessing some network folder/share and your waiting for the timeout. You need to look in the trace log in instruments (bottom panel) when launching your apps, try to narrow it down to a file/location/share etc

 

[Chippy99]

Thanks once again. It seems to be happening at /net/Backups.backupdb

Doing some digging, "net" is a hidden folder. If I show all hidden files in Finder, I can find net in "Computer". I can't open it from the Computer folder, but I can open it by navigating to /net and it appears empty.
Any ideas?

 

[Anim]

Thanks once again. It's not obvious, looking at the trace, because the hang doesn't appear at exactly the same point every launch. But it *seems* to be happening somewhere around here:
Image

I don't mean entry 22,608 - just somewhere on this page.

Any ideas? Also, where is your "home" directory? If I navigate to /home in finder, I note there is no Backups.backupdb file to be found - the home folder is empty.

Well, I have now read that /net refers to the system hosts file when searching for mounts and shares so there is a point when it scans this in your screenshot.

Edit: See this post: https://forums.macrumors.com/threads/1029147/

You can see from the log that it is searching for the backupdb file (fileExistsAtPath). This I am sure is the Time Machine Backup file so tread carefully, don't delete it etc.

Your home folder can be found in finder by clicking the menu "GO" then selecting Computer. Then in your hard drive, find the users folder, in there you can see all the "home" folders for each user on your system.

I still think this "application pausing" is down to some old mount point which is now unavailable. Hmmm, you could try booting your mac into safe mode and see if iMovie loads, not entirly sure it is supported under safe mode though.

http://support.apple.com/kb/HT1564?viewlocale=en_US&locale=en_US

I don't know if this would work but can you delete your ethernet connection in network preferences, reboot, then re-add it, with the hope this deletes all previous mappings and shares?

Anim

 

[Chippy99]

Thank you again - you are being very helpful and I really appreciate it

Sorry, but as you will see, I did some digging of my own and edited my post quite a bit.

I'll try deleting my Ethernet connection and see what that does...

EDIT: Deleted Ethernet and recreated and unfortunately it made no difference :-(

 

[Anim]

Thank you again - you are being very helpful and I really appreciate it

Sorry, but as you will see, I did some digging of my own and edited my post quite a bit.

I'll try deleting my Ethernet connection and see what that does...

EDIT: Deleted Ethernet and recreated and unfortunately it made no difference :-(

Ok, well I'm stumped too. I would have another re-read of this entire thread as edits have been made along the way so maybe you missed something important from Switchfoot's findings?

Hopefully somebody else can chime in here who knows OSX troubleshooting more than me.

Cheers
Anim

 

[Chippy99]

I think I am onto something. If I type

stat /net/Backups.backupdb

I get the exact same 25 second hang, followed by

stat: /net/Backups.backupdb: stat: Operation timed out



I got this from http://arstechnica.com/civis/viewtopic.php?p=25987533

I suspect if I edit the hosts file to point /net/Backups.backupdb at 127.0.0.1 or suchlike, this will fix it. I'll report back...

EDIT

Damn! Still no joy. Creating the hosts entry does fix the stat hang above, but does not fix FCPX. I am sure it's something to do with this though.

 

[Anim]

I think I am onto something. If I type

stat /net/Backups.backupdb

I get the exact same 25 second hang, followed by

stat: /net/Backups.backupdb: stat: Operation timed out



I got this from http://arstechnica.com/civis/viewtopic.php?p=25987533

I suspect if I edit the hosts file to point /net/Backups.backupdb at 127.0.0.1 or suchlike, this will fix it. I'll report back...

I just unmounted /net by following this guide:
http://osxdaily.com/2013/11/24/slow-open-save-dialog-problem-mac-os-x/

....and now stat on backups.backupdb returns straight back with file not found.

Please note I have not looked into what effect this will have on the rest of the system so will need to keep an eye out.

 

[Chippy99]

Thanks again.

If more diagnostics are needed, I found this: If I connect to the US over VPN (I am in the UK) then launch FCPX, it launches instantly.

Turn off VPN, it still launches instantly

Flush DNS cache, and the hang reappears.

Seems my ISP is returning garbage to a DNS lookup of Backups.backupdb. I can verify this by pinging Backups.backupdb. It does not say no such host, it starts pinging away.

I tried your fix. IT WORKS! Don't know what else it has broken mind you!

Cheers and 1,000 thanks again!

 

[Anim]

Thanks again.

If more diagnostics are needed, I found this: If I connect to the US over VPN (I am in the UK) then launch FCPX, it launches instantly.

Turn off VPN, it still launches instantly

Flush DNS cache, and the hang reappears.

Seems my ISP is returning garbage to a DNS lookup of Backups.backupdb. I can verify this by pinging Backups.backupdb. It does not say no such host, it starts pinging away.

I will read through your proposed fix, but I am a little wary in case it mucks other things up.

Cheers

Try it only takes a second and if no luck just uncomment the line. Read the user comments at the bottom of the post for some peace of mind.

It didn't mess with my Time Machine backup as that was the first thing I tested afterwards.

----------

I tried your fix. IT WORKS! Don't know what else it has broken mind you!

Cheers and 1,000 thanks again!

Awesome, good teamwork

 

[Chippy99]

Try it only takes a second and if no luck just uncomment the line. Read the user comments at the bottom of the post for some peace of mind.

It didn't mess with my Time Machine backup as that was the first thing I tested afterwards.

----------



Awesome, good teamwork

Yes indeedy

I just noticed you're from Macclesfield. I used to live in Poynton - my mum still lives there now!

 

[Anim]

Yes indeedy

I just noticed you're from Macclesfield. I used to live in Poynton - my mum still lives there now!

I'm actually from Bollington ha. I went to Poynton School for 2 years in my youth but after being thumped by the deputy head I forced them to transfer me to Tytherington High where all my mates were lol.

Small world

 

[Chippy99]

I'm actually from Bollington ha. I went to Poynton School for 2 years in my youth but after being thumped by the deputy head I forced them to transfer me to Tytherington High where all my mates were lol.

Small world

The Bulls Head at Kerridge is one of my favourite pubs, with the brilliant tapas menu. I go there quite often!

 

[Anim]

The Bulls Head at Kerridge is one of my favourite pubs, with the brilliant tapas menu. I go there quite often!

haha, I was in there last week. Ordered whitebait but they had run out so got calamari instead, was expecting tiny dishes but they were all huge for tapas and really tasty. The San Miguel beer is spot on too. Summer is coming so that little beer garden will be getting busy. I walk my dog past it towards White Nancy quite often.

So where you from?