Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Hanging when updating Libraries from older versions #305

Open
stevef243 opened this issue Dec 13, 2023 · 22 comments
Open

Hanging when updating Libraries from older versions #305

stevef243 opened this issue Dec 13, 2023 · 22 comments

Comments

@stevef243
Copy link

stevef243 commented Dec 13, 2023

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:

  • Mac Studio
  • Sonoma
  • Final Cut Pro 10.7.x

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 latenitefilms changed the title FCP 10.7 hanging when updating Data Bases Hanging when updating Libraries from older versions Dec 13, 2023
@latenitefilms latenitefilms self-assigned this Dec 13, 2023
@latenitefilms
Copy link
Contributor

@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
Copy link
Author

stevef243 commented Dec 16, 2023 via email

@latenitefilms
Copy link
Contributor

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
Copy link
Author

stevef243 commented Dec 16, 2023 via email

@stevef243
Copy link
Author

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

@stevef243
Copy link
Author

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

@latenitefilms latenitefilms reopened this Dec 28, 2023
@stevef243
Copy link
Author

@stevef243
Copy link
Author

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

@stevef243
Copy link
Author

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
Copy link
Author

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

@stevef243
Copy link
Author

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

@latenitefilms
Copy link
Contributor

@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
Copy link
Author

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

@latenitefilms
Copy link
Contributor

Do you have those crash logs you can share?

Do you want me to close this issue?

@stevef243
Copy link
Author

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

@latenitefilms
Copy link
Contributor

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
Copy link
Author

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

@latenitefilms
Copy link
Contributor

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
Copy link
Author

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
Copy link
Contributor

Try looking at the Finder Level?

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

@latenitefilms
Copy link
Contributor

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
Copy link
Author

yes checked that it's empty

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

2 participants