CommandPost / FCPCafe

FCP Cafe Website
https://fcp.cafe
MIT License
26 stars 15 forks source link

Hanging when updating Libraries from older versions #305

Open stevef243 opened 6 months ago

stevef243 commented 6 months ago

Apple Feedback Assistant ID: FB13464670

DESCRIBE THE BUG: FCP is hanging when loading Data Bases, which need updating. Noticed this now quite a few times


TO REPRODUCE: cant always reproduce but several times FCP hang after updating the DB. Once you Force Quite FCP and restart the DB seems to behave normally


EXPECTED BEHAVIOUR: FCP not hanging when updating a DB


SCREENSHOTS: CleanShot 2023-12-13 at 11 21 30@2x


SPECS:


ADDITIONAL COMMENTS: no crash logs again in folder

Apps Diagnostic link https://drive.google.com/file/d/1l3_84ylJObzp30LH_B_vJyU8iaobVxTv/view?usp=sharing

latenitefilms commented 6 months ago

@stevef243 - Apple says:

The sysdiagnose you provided did not show any hangs or spins pertaining to FCP. Can you please attach a sysdiagnose from the computer that had the issue?

I will send them your previous log files.

stevef243 commented 6 months ago

lol that’s Apple the system log obviously was from the computer I had the issue with. Now on Holliday so out for two weeks On 16 Dec 2023 at 14:36 +1100, Chris Hocking @.***>, wrote:

@stevef243 - Apple says:

The sysdiagnose you provided did not show any hangs or spins pertaining to FCP. Can you please attach a sysdiagnose from the computer that had the issue? I will send them your previous log files. — Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you were mentioned.Message ID: @.***>

latenitefilms commented 6 months ago

It's possible I could have accidentally included my system logs - as the Feedback Assistant Mac app will automatically grab all your log files - although I did link to this GitHub issue and your Google Drive link in the feedback.

stevef243 commented 6 months ago

I can’t check now but pretty sure I provided correct file and certainly created log on my work machine as that’s the only one I’m using for FCP On 16 Dec 2023 at 16:21 +1100, Chris Hocking @.***>, wrote:

It's possible I could have accidentally included my system logs - as the Feedback Assistant Mac app will automatically grab all your log files - although I did link to this GitHub issue and your Google Drive link in the feedback. — Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you were mentioned.Message ID: @.***>

stevef243 commented 6 months ago

This seems to have been fixed in 10.7.1 just updated many libraries without hangs

stevef243 commented 6 months ago

actually still does happen especially with bigger libraries ![Uploading CleanShot 2023-12-28 at 11.53.21@2x.jpg…]()

stevef243 commented 6 months ago

App Diagnostics https://drive.google.com/file/d/1U-dAFhS0RbbCQVIcdW5eQW99IwRKr4xG/view?usp=sharing

stevef243 commented 6 months ago

and again need to force quite FCP ![Uploading CleanShot 2023-12-28 at 12.03.11@2x.jpg…]()

stevef243 commented 6 months ago

strange did 20 Liabraries no issue and now FCP hangs on each Library

Date/Time: 2023-12-28 12:02:28.644 +1100 End time: 2023-12-28 12:06:35.475 +1100 OS Version: macOS 14.2.1 (Build 23C71) Architecture: arm64e Report Version: 44 Incident Identifier: 770CCFAA-78FA-458B-9670-46F893008B67 Share With Devs: Yes

Data Source: Stackshots Shared Cache: F9DDD844-7F3F-34BD-BE29-F0C72D5E5449 slid base address 0x1821b8000, slide 0x21b8000 (System Primary) Shared Cache: AA32606F-3F8A-36DC-89AB-9C1BD7BF3104 slid base address 0x7ff815660000, slide 0x15660000 (Rosetta) Shared Cache: 2BF5B417-CD87-34D6-A876-2BBD0F25330F slid base address 0x1a2a74000, slide 0x22a74000 (DriverKit)

Command: Final Cut Pro Path: /Applications/Final Cut Pro.app/Contents/MacOS/Final Cut Pro Identifier: com.apple.FinalCut Version: 10.7.1 (410082) Build Version: 12 Product Build Version: 1D1004 Project Name: ProEditor Source Version: 41000082016000000 Team ID: PTN9T2S29T App Item ID: 424389933 App External ID: 861999800 Is First Party: Yes Architecture: arm64 Parent: launchd [1] PID: 17478 Time Since Fork: 621s

Event: hang Duration: 246.83s Duration Sampled: 2.10s (process was unresponsive for 245 seconds before sampling) Steps: 21 (100ms sampling interval)

Hardware model: Mac13,1 Active cpus: 10 HW page size: 16384 VM page size: 16384

Time Since Boot: 18608s Time Awake Since Boot: 18608s Time Since Wake: n/a (machine hasn't slept)

Fan speed: 1382 rpm Total CPU Time: 4.963s (9.4G cycles, 16.2G instructions, 0.58c/i) Advisory levels: Battery -> 2, User -> 2, ThermalPressure -> 0, Combined -> 2 Free disk space: 77.67 GB/926.35 GB, low space threshold 3072 MB Vnodes Available: 84.55% (222516/263168)

Processes reached dispatch thread soft limit (64): Final Cut Pro [17478]

Preferred User Language: en-AU, de-AU Country Code: AU Keyboards: Australian, German-DIN-2137 OS Cryptex File Extents: 2252


Timeline format: stacks are sorted chronologically Use -i and -heavy to re-report with count sorting

Heaviest stack for the main thread of the target process: 21 start + 2360 (dyld + 24800) [0x1822750e0] 21 LKApplicationMain + 204 (LunaKit + 441520) [0x100ef7cb0] 21 -[NSApplication run] + 476 (AppKit + 183632) [0x185ea1d50] 21 -[NSApplication(NSEventRouting) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 716 (AppKit + 8468864) [0x186688980] 21 _DPSNextEvent + 660 (AppKit + 235684) [0x185eae8a4] 21 _BlockUntilNextEventMatchingListInModeWithFilter + 76 (HIToolbox + 196572) [0x18cc7ffdc] 21 ReceiveNextEventCommon + 220 (HIToolbox + 196824) [0x18cc800d8] 21 RunCurrentEventLoopInMode + 292 (HIToolbox + 197704) [0x18cc80448] 21 CFRunLoopRunSpecific + 608 (CoreFoundation + 506284) [0x1826d19ac] 21 CFRunLoopRun + 776 (CoreFoundation + 508684) [0x1826d230c] 21 CFRunLoopDoObservers + 532 (CoreFoundation + 511196) [0x1826d2cdc] 21 CFRUNLOOP_IS_CALLING_OUT_TO_AN_OBSERVER_CALLBACK_FUNCTION + 36 (CoreFoundation + 511472) [0x1826d2df0] 21 _NSRunLoopObserverCreateWithHandler_block_invoke + 64 (AppKit + 11564376) [0x18697c558] 21 62+[CATransaction(NSCATransaction) NS_setFlushesWithDisplayLink]_block_invoke + 272 (AppKit + 1449672) [0x185fd6ec8] 21 CA::Transaction::commit() + 648 (QuartzCore + 11932) [0x18a794e9c] 21 CA::Context::commit_transaction(CA::Transaction, double, double) + 512 (QuartzCore + 1724964) [0x18a937224] 21 CA::Layer::display_if_needed(CA::Transaction) + 736 (QuartzCore + 131304) [0x18a7b20e8] 21 -[CALayer _display] + 280 (QuartzCore + 133660) [0x18a7b2a1c] 21 -[FFEffectLibraryItemView loadIconIfVisible] + 248 (Flexo + 7600972) [0x1083a3b4c] 21 -[FFEffectLibraryThumbnailRequestManager copyCachedImageForItem:] + 508 (Flexo + 8273804) [0x108447f8c] 21 -[FFImage(CGImageRef) newCGImageRef] + 116 (Flexo + 890056) [0x107d3d4c8] 21 -[FFImageRepCGImage initWithRepresentation:options:] + 580 (Flexo + 886916) [0x107d3c884] 21 -[FFImageRepSegmentReadSample newPixelBufferWithFormatByFlatteningIfNeeded:roi:pixelTransform:nonStandardAlphaOK:flattenOptions:getRawPixelsWithoutSourceConform:workingSpace:] + 80 (Flexo + 923008) [0x107d45580] 21 -[FFImageRepSegmentReadSample _ensureFinalImage:] + 96 (Flexo + 922824) [0x107d454c8] 21 -[FFSegmentReadSample waitForState:enablePriorityInherit:why:beforeDate:] + 560 (Flexo + 917404) [0x107d43f9c] 21 __psynch_cvwait + 8 (libsystem_kernel.dylib + 20588) [0x1825b906c] 21 psynch_cvcontinue + 0 (com.apple.kec.pthread + 18204) [0xfffffe000b7eac7c]

Process: Final Cut Pro [17478] UUID: 5EE29289-35A0-3240-9FA2-0E047AA91546 Path: /Applications/Final Cut Pro.app/Contents/MacOS/Final Cut Pro Identifier: com.apple.FinalCut Version: 10.7.1 (410082) Build Version: 12 Product Build Version: 1D1004 Project Name: ProEditor Source Version: 41000082016000000 Team ID: PTN9T2S29T App Item ID: 424389933 App External ID: 861999800 Is First Party: Yes Shared Cache: F9DDD844-7F3F-34BD-BE29-F0C72D5E5449 slid base address 0x1821b8000, slide 0x21b8000 (System Primary) Architecture: arm64 Parent: launchd [1] UID: 501 Footprint: 3465.12 MB Time Since Fork: 621s Num samples: 21 (1-21) CPU Time: 0.024s (36.6M cycles, 19.9M instructions, 1.84c/i) Note: Unresponsive for 245 seconds before sampling Note: 4 idle work queue threads omitted Dispatch Thread Soft Limit Reached: 64 (too many dispatch threads blocked in synchronous operations)

Thread 0x34e5eb DispatchQueue "com.apple.main-thread"(1) 21 samples (1-21) priority 47 (base 47) <process frontmost, thread QoS user interactive (requested user interactive), process unclamped, process received importance donation from WindowServer [164], process received importance donation from distnoted [407], IO tier 0> 21 start + 2360 (dyld + 24800) [0x1822750e0] 1-21 21 LKApplicationMain + 204 (LunaKit + 441520) [0x100ef7cb0] 1-21 21 -[NSApplication run] + 476 (AppKit + 183632) [0x185ea1d50] 1-21 21 -[NSApplication(NSEventRouting) _nextEventMatchingEventMask:untilDate:inMode:dequeue:] + 716 (AppKit + 8468864) [0x186688980] 1-21 21 _DPSNextEvent + 660 (AppKit + 235684) [0x185eae8a4] 1-21 21 _BlockUntilNextEventMatchingListInModeWithFilter + 76 (HIToolbox + 196572) [0x18cc7ffdc] 1-21 21 ReceiveNextEventCommon + 220 (HIToolbox + 196824) [0x18cc800d8] 1-21 21 RunCurrentEventLoopInMode + 292 (HIToolbox + 197704) [0x18cc80448] 1-21 21 CFRunLoopRunSpecific + 608 (CoreFoundation + 506284) [0x1826d19ac] 1-21 21 CFRunLoopRun + 776 (CoreFoundation + 508684) [0x1826d230c] 1-21 21 CFRunLoopDoObservers + 532 (CoreFoundation + 511196) [0x1826d2cdc] 1-21 21 CFRUNLOOP_IS_CALLING_OUT_TO_AN_OBSERVER_CALLBACK_FUNCTION + 36 (CoreFoundation + 511472) [0x1826d2df0] 1-21 21 _NSRunLoopObserverCreateWithHandler_block_invoke + 64 (AppKit + 11564376) [0x18697c558] 1-21 21 62+[CATransaction(NSCATransaction) NS_setFlushesWithDisplayLink]_block_invoke + 272 (AppKit + 1449672) [0x185fd6ec8] 1-21 21 CA::Transaction::commit() + 648 (QuartzCore + 11932) [0x18a794e9c] 1-21 21 CA::Context::commit_transaction(CA::Transaction, double, double) + 512 (QuartzCore + 1724964) [0x18a937224] 1-21 21 CA::Layer::display_if_needed(CA::Transaction) + 736 (QuartzCore + 131304) [0x18a7b20e8] 1-21 21 -[CALayer _display] + 280 (QuartzCore + 133660) [0x18a7b2a1c] 1-21 21 -[FFEffectLibraryItemView loadIconIfVisible] + 248 (Flexo + 7600972) [0x1083a3b4c] 1-21 21 -[FFEffectLibraryThumbnailRequestManager copyCachedImageForItem:] + 508 (Flexo + 8273804) [0x108447f8c] 1-21 21 -[FFImage(CGImageRef) newCGImageRef] + 116 (Flexo + 890056) [0x107d3d4c8] 1-21 21 -[FFImageRepCGImage initWithRepresentation:options:] + 580 (Flexo + 886916) [0x107d3c884] 1-21 21 -[FFImageRepSegmentReadSample newPixelBufferWithFormatByFlatteningIfNeeded:roi:pixelTransform:nonStandardAlphaOK:flattenOptions:getRawPixelsWithoutSourceConform:workingSpace:] + 80 (Flexo + 923008) [0x107d45580] 1-21 21 -[FFImageRepSegmentReadSample _ensureFinalImage:] + 96 (Flexo + 922824) [0x107d454c8] 1-21 21 -[FFSegmentReadSample waitForState:enablePriorityInherit:why:beforeDate:] + 560 (Flexo + 917404) [0x107d43f9c] 1-21 21 __psynch_cvwait + 8 (libsystem_kernel.dylib + 20588) [0x1825b906c] 1-21 21 psynch_cvcontinue + 0 (com.apple.kec.pthread + 18204) [0xfffffe000b7eac7c] 1-21

Thread 0x352555 DispatchQueue "com.apple.flexo.FFSharedAudioUnitPoolUpdateQueue"(5509) 21 samples (1-21) priority 4 (base 4) <process frontmost, thread QoS background (requested background, workloop servicer override background), process unclamped, thread darwinbg, process received importance donation from WindowServer [164], process received importance donation from distnoted [407], IO tier 2> 21 start_wqthread + 8 (libsystem_pthread.dylib + 7728) [0x1825f0e30] 1-21 21 _pthread_wqthread + 288 (libsystem_pthread.dylib + 12564) [0x1825f2114] 1-21 21 _dispatch_workloop_worker_thread + 404 (libdispatch.dylib + 89744) [0x182456e90] 1-21 21 _dispatch_root_queue_drain_deferred_wlh + 288 (libdispatch.dylib + 91676) [0x18245761c] 1-21 21 _dispatch_lane_invoke + 432 (libdispatch.dylib + 47624) [0x18244ca08] 1-21 21 _dispatch_lane_serial_drain + 956 (libdispatch.dylib + 44916) [0x18244bf74] 1-21 21 _dispatch_client_callout + 20 (libdispatch.dylib + 14608) [0x182444910] 1-21 21 _dispatch_call_block_and_release + 32 (libdispatch.dylib + 7352) [0x182442cb8] 1-21 21 -[FFSharedAudioUnitPool _updateAudioUnitAllocation] + 340 (Flexo + 4122176) [0x108052640] 1-21 21 -[FFSharedAudioUnitPoolEntry updateAudioUnitAllocationWithTimeLimit:] + 224 (Flexo + 4117404) [0x10805139c] 1-21 21 -[FFSharedAudioUnitPoolEntry updateAudioUnitAllocationForFormatKey:timeLimit:] + 500 (Flexo + 4116780) [0x10805112c] 1-21 21 _dispatch_semaphore_wait_slow + 132 (libdispatch.dylib + 17756) [0x18244555c] 1-21 21 semaphore_wait_trap + 8 (libsystem_kernel.dylib + 6128) [0x1825b57f0] 1-21 *21 ??? (kernel.release.t6000 + 644980) [0xfffffe00085d5774] 1-21

Thread 0x34f62a DispatchQueue "com.apple.root.default-qos"(13) 21 samples (1-21) priority 37 (base 37) <process frontmost, thread QoS user initiated (requested user initiated), process unclamped, process received importance donation from WindowServer [164], process received importance donation from distnoted [407], IO tier 0> 21 start_wqthread + 8 (libsystem_pthread.dylib + 7728) [0x1825f0e30] 1-21 21 _pthread_wqthread + 228 (libsystem_pthread.dylib + 12504) [0x1825f20d8] 1-21 21 _dispatch_worker_thread2 + 156 (libdispatch.dylib + 88580) [0x182456a04] 1-21 21 _dispatch_root_queue_drain + 392 (libdispatch.dylib + 86516) [0x1824561f4] 1-21 21 _dispatch_queue_override_invoke + 928 (libdispatch.dylib + 27228) [0x182447a5c] 1-21 21 _dispatch_client_callout + 20 (libdispatch.dylib + 14608) [0x182444910] 1-21 21 _dispatch_call_block_and_release + 32 (libdispatch.dylib + 7352) [0x182442cb8] 1-21 21 84+[FFSharedAudioUnit instantiateWithEffectID:sampleRate:numChannels:completionBlock:]_block_invoke + 220 (Flexo + 4111716) [0x10804fd64] 1-21 21 80-[FFAudioUnitEffect _requestMasterUnitForEffectActivation:usingCompletionBlock:]_block_invoke + 352 (Flexo + 1491284) [0x107dd0154] 1-21 21 _dispatch_semaphore_wait_slow + 132 (libdispatch.dylib + 17756) [0x18244555c] 1-21 21 semaphore_wait_trap + 8 (libsystem_kernel.dylib + 6128) [0x1825b57f0] 1-21 *21 ??? (kernel.release.t6000 + 644980) [0xfffffe00085d5774] 1-21

Thread 0x350da8 DispatchQueue "com.apple.root.default-qos"(13) 21 samples (1-21) priority 37 (base 37) <process frontmost, thread QoS user initiated (requested user initiated), process unclamped, process received importance donation from WindowServer [164], process received importance donation from distnoted [407], IO tier 0> 21 start_wqthread + 8 (libsystem_pthread.dylib + 7728) [0x1825f0e30] 1-21 21 _pthread_wqthread + 228 (libsystem_pthread.dylib + 12504) [0x1825f20d8] 1-21 21 _dispatch_worker_thread2 + 156 (libdispatch.dylib + 88580) [0x182456a04] 1-21 21 _dispatch_root_queue_drain + 392 (libdispatch.dylib + 86516) [0x1824561f4] 1-21 21 _dispatch_queue_override_invoke + 928 (libdispatch.dylib + 27228) [0x182447a5c] 1-21 21 _dispatch_client_callout + 20 (libdispatch.dylib + 14608) [0x182444910] 1-21 21 _dispatch_call_block_and_release + 32 (libdispatch.dylib + 7352) [0x182442cb8] 1-21 21 84+[FFSharedAudioUnit instantiateWithEffectID:sampleRate:numChannels:completionBlock:]_block_invoke + 220 (Flexo + 4111716) [0x10804fd64] 1-21 21 80-[FFAudioUnitEffect _requestMasterUnitForEffectActivation:usingCompletionBlock:]_block_invoke + 352 (Flexo + 1491284) [0x107dd0154] 1-21 21 _dispatch_semaphore_wait_slow + 132 (libdispatch.dylib + 17756) [0x18244555c] 1-21 21 semaphore_wait_trap + 8 (libsystem_kernel.dylib + 6128) [0x1825b57f0] 1-21 *21 ??? (kernel.release.t6000 + 644980) [0xfffffe00085d5774] 1-21

Thread 0x350f5e DispatchQueue "com.apple.root.default-qos"(13) 21 samples (1-21) priority 37 (base 37) <process frontmost, thread QoS user initiated (requested user initiated), process unclamped, process received importance donation from WindowServer [164], process received importance donation from distnoted [407], IO tier 0> 21 start_wqthread + 8 (libsystem_pthread.dylib + 7728) [0x1825f0e30] 1-21 21 _pthread_wqthread + 228 (libsystem_pthread.dylib + 12504) [0x1825f20d8] 1-21 21 _dispatch_worker_thread2 + 156 (libdispatch.dylib + 88580) [0x182456a04] 1-21 21 _dispatch_root_queue_drain + 392 (libdispatch.dylib + 86516) [0x1824561f4] 1-21 21 _dispatch_queue_override_invoke + 928 (libdispatch.dylib + 27228) [0x182447a5c] 1-21 21 _dispatch_client_callout + 20 (libdispatch.dylib + 14608) [0x182444910] 1-21 21 _dispatch_call_block_and_release + 32 (libdispatch.dylib + 7352) [0x182442cb8] 1-21 21 84+[FFSharedAudioUnit instantiateWithEffectID:sampleRate:numChannels:completionBlock:]_block_invoke + 220 (Flexo + 4111716) [0x10804fd64] 1-21 21 80-[FFAudioUnitEffect _requestMasterUnitForEffectActivation:usingCompletionBlock:]_block_invoke + 352 (Flexo + 1491284) [0x107dd0154] 1-21 21 _dispatch_semaphore_wait_slow + 132 (libdispatch.dylib + 17756) [0x18244555c] 1-21 21 semaphore_wait_trap + 8 (libsystem_kernel.dylib + 6128) [0x1825b57f0] 1-21 *21 ??? (kernel.release.t6000 + 644980) [0xfffffe00085d5774] 1-21

stevef243 commented 6 months ago

This one even gave me FCP Crash window https://drive.google.com/file/d/1Ox0Iz8TDvwsRoyl_XSLQ2qc6pCUY36GJ/view?usp=sharing

stevef243 commented 6 months ago

When re-opening FCP, the Libraries, which seemed to have stuck and crashed FCP, are updated and seem to work.

latenitefilms commented 6 months ago

@stevef243 - Apple has sent me this:

We will need to see an actual hang log or a sample of FCP while the app is hanging during this process. Can you capture and submit that info (via Activity Monitor) if the problem can be reproduced?

stevef243 commented 6 months ago

have finally all Libraries updated with quite a few crashes so at the moment could not reproduce

latenitefilms commented 5 months ago

Do you have those crash logs you can share?

Do you want me to close this issue?

stevef243 commented 5 months ago

These crash logs should be from the updates Archive 2.zip

latenitefilms commented 5 months ago

Do you have backups of the libraries before updating, so that you can reproduce the hang? I don't think Apple will be able to do much more without a hang log or sample of the FCP whilst hanging.

stevef243 commented 5 months ago

no, I update the backup also. What exactly is a hanging log? And what sample while it's hanging, just for further reference?

latenitefilms commented 5 months ago

Hanging is when you force quit the app, rather than it crashing by itself. Hanging logs end up in the same place as crash logs.

You can Sample a process using Activity Monitor:

Screenshot 2024-01-09 at 8 47 32 am

It basically just creates log files of everything Final Cut Pro was doing at the time of the sample.

stevef243 commented 5 months ago

Strange, I have all crash logs from that time 1st Jan , but there is not a single hanging log in the same location CleanShot 2024-01-09 at 08 51 58@2x

latenitefilms commented 5 months ago

Try looking at the Finder Level?

https://fcp.cafe/bugtracker/#finding-crash-logs

latenitefilms commented 5 months ago

Hangs might also be in the "Spin Reports" section? I'm not actually sure what Spin Reports refers to, as the documentation is unclear:

https://support.apple.com/en-au/guide/console/cnsl664be99a/mac

stevef243 commented 5 months ago

yes checked that it's empty