VDMX Debugging Plug In (How to find out 8727 Crashes)

#1

Here’s a follow up to the PlugIn I posted in response to this Topic:

Here is a trimmed portion of the log output for a successful opening of my project on 8724, It is quite a larger project with over 35 plugins.
As you can see all the plugins load fine on 8724

See reply for same project opening on 8727

0626 xx:50.172 VDMX5[] -[ProjectFileManager _openProject:presentAsNewProject:] … /Volumes/Performance New/zzzzzz 2021 Visuals Folder/VDMX Project Files/Mac 10.11/Hollywood/2021 06 New Layers : Masks/Hollywood Kaptain VC 02A.vdmx5, 0
0626 xx:30.600 VDMX5[] triggering preset named (null)
0626 xx:30.638 VDMX5[] -[ProjPresets _actuallyTriggerPreset:] - FINISHED
0626 xx:37.996 VDMX5[] triggering preset named Preset 1
0626 xx:41.572 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:41.572 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:42.76 [VDMX] Technomorph Debug Logging

Plug In Position:1
After Info:INIT ****************************************
Before Info:Clock

0626 xx:43.128 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:43.128 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:43.42 [VDMX] Technomorph Debug Logging

Plug In Position:3
After Info:Clock
Before Info:Audio

0626 xx:44.509 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:44.509 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:44.78 [VDMX] Technomorph Debug Logging

Plug In Position:5
After Info:Audio
Before Info:Audio Control Surfaces

0626 xx:45.133 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:45.133 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:45.39 [VDMX] Technomorph Debug Logging

Plug In Position:9
After Info:Audio Control Surfaces
Before Info:Bins

0626 xx:46.177 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:46.177 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:46.54 [VDMX] Technomorph Debug Logging

Plug In Position:16
After Info:Bins
Before Info:Blend Control Surfaces and C Report Surfaces

0626 xx:48.022 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:48.022 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:48.28 [VDMX] Technomorph Debug Logging

Plug In Position:23
After Info: C Report Surfaces
Before Info:Comm Display and Layer Picker Control Surfave

0626 xx:48.535 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:48.535 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:48.79 [VDMX] Technomorph Debug Logging

Plug In Position:26
After Info:Layer Picker Control Surfave
Before Info:All LFOs - NINE of them

0626 xx:52.430 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:52.430 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:52.73 [VDMX] Technomorph Debug Logging

Plug In Position:36
After Info:LFOs
Before Info:Mask Pick CS and Previews

0626 xx:53.059 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:53.059 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo

0626 xx:53.32 [VDMX] Technomorph Debug Logging

Plug In Position:40
After Info:Previews
Before Info:Step Sequencers

0626 xx:53.791 VDMX5[] -[VVVuoComposition _loadComposition]
0626 xx:53.791 VDMX5[] path is /Users/kerry/Library/Application Support/VDMX/plugins/Technomorph Debug Log.vuo
0626 xx:54.171 VDMX5[] -[ProjPresets _actuallyTriggerPreset:] - FINISHED

0626 xx:54.06 [VDMX] Technomorph Debug Logging

Plug In Position:45
After Info:Seq Control Surface Report
Before Info:END OF PLUGIN LOADING ********************************

0 Likes

VDMX Beta 8.7.2.7 now ready for testing!
#2

Now here is a portion of the Log from trying to open the same project on 8727 (that crashes)

You can see that it crashes when it tries to load my Blend Control Surfaces PlugIn.

See next reply for how to diagnose further.

0626 xx:02.972 VDMX[] ApplePersistenceIgnoreState: Existing state will not be touched. New state will be written to (null)
0626 xx:03.315 VDMX[] +[VuoCache configGlobalCacheWithSavePath:]
0626 xx:03.349 VDMX[] vuoFmwkVersString is 2.3.0.11546, lastVuoFmwkVersString is 2.3.0.11546
0626 xx:03.463 VDMX[] +[VVStatChecker populateDefaultRenderingPrefs]
0626 xx:04.165 VDMX[] GPU vendor array is (
“NVIDIA Corporation”
)
0626 xx:04.165 VDMX[] renderer name is NVIDIA GeForce 9400M OpenGL Engine
0626 xx:04.599 VDMX[] -[VVLightingManager init]
0626 xx:10.473 VDMX[] err: MDItem created but no content type detected at path - /Volumes/Audio Projects
0626 xx:12.010 VDMX[] -[VVOSCQueryServer start]
0626 xx:12.011 VDMX[] web server running on port 2345, try connecting to http://localhost:2345
0626 xx:12.012 VDMX[] -[VVLightingManager stop]
0626 xx:12.013 VDMX[] QCAA is 0
0626 xx:12.013 VDMX[] QC AA is 0
0626 xx:12.013 VDMX[] safeQCRender is 1
0626 xx:12.013 VDMX[] skipCanvasRender is 0
0626 xx:12.013 VDMX[] applyFXToDuplicateFrames is 0
0626 xx:12.014 VDMX[] CIQCWarningType is 2
0626 xx:12.430 VDMX[] VBL is 1
0626 xx:12.430 VDMX[] display output mode is 0
0626 xx:12.430 VDMX[] displayDrivenRenderer is 1
0626 xx:12.430 VDMX[] doubleBufferedOutputs is 1
0626 xx:12.495 VDMX[] err: -[ObjectHolder initWithZWRObject:] - BAIL
0626 xx:12.495 VDMX[] err: -[ObjectHolder initWithZWRObject:] - BAIL
0626 xx:17.987 VDMX[] err: bailing, this is our own query server!
0626 xx:36.910 VDMX[] -[ProjectFileManager _openProject:presentAsNewProject:] … /Volumes/Performance New/zzzzzz 2021 Visuals Folder/VDMX Project Files/Mac 10.11/Hollywood/2021 06 New Layers : Masks/Hollywood Kaptain VC 02A.vdmx5, 0
2021-06-26 03:52:21.326 VDMX[] triggering preset named (null)
2021-06-26 03:52:21.470 VDMX[] -[ProjPresets _actuallyTriggerPreset:] - FINISHED
2021-06-26 03:52:37.328 VDMX[] triggering preset named Preset 1

2021-06-26 03:52:42.13 [VDMX] Technomorph Debug Logging

Plug In Position:1
After Info:INIT ****************************************
Before Info:Clock

2021-06-26 03:52:42.67 [VDMX] Technomorph Debug Logging

Plug In Position:3
After Info:Clock
Before Info:Audio

2021-06-26 03:52:43.98 [VDMX] Technomorph Debug Logging

Plug In Position:5
After Info:Audio
Before Info:Audio Control Surfaces

2021-06-26 03:52:44.42 [VDMX] Technomorph Debug Logging

Plug In Position:9
After Info:Audio Control Surfaces
Before Info:Bins

2021-06-26 03:52:45.53 [VDMX] Technomorph Debug Logging

Plug In Position:16
After Info:Bins
Before Info:Blend Control Surfaces and C Report Surfaces

e[38;5;157m# pid=17493 t= 13.8438s VuoTelemetry.c:253 vuoSend() The connection between the composition and runner failed when trying to send a message of type 0 on ‘VuoControl’ (Operation cannot be accomplished in current state)e[0m
e[38;5;157m# pid=17493 t= 13.8444s VuoRuntimeState.cc:446 killProcessAfterTimeout_block_invoke_2() Warning: Waited 10 seconds for the composition to cleanly shut down, but it’s still running. Now I’m force-quitting it.e[0m
e[38;5;159m# pid=17495 t= 13.2908s VuoTelemetry.c:253 vuoSend() The connection between the composition and runner failed when trying to send a message of type 0 on ‘VuoControl’ (Operation cannot be accomplished in current state)e[0m
e[38;5;159m# pid=17495 t= 13.2914s VuoRuntimeState.cc:446 killProcessAfterTimeout_block_invoke_2() Warning: Waited 10 seconds for the composition to cleanly shut down, but it’s still running. Now I’m force-quitting it.e[0m
e[38;5;165m# pid=17501 t= 12.0410s VuoTelemetry.c:253 vuoSend() The connection between the composition and runner failed when trying to send a message of type 0 on ‘VuoControl’ (Operation cannot be accomplished in current state)e[0m
e[38;5;165m# pid=17501 t= 12.0416s VuoRuntimeState.cc:446 killProcessAfterTimeout_block_invoke_2() Warning: Waited 10 seconds for the composition to cleanly shut down, but it’s still running. Now I’m force-quitting it.e[0m
e[38;5;167m# pid=17503 t= 11.6102s VuoTelemetry.c:253 vuoSend() The connection between the composition and runner failed when trying to send a message of type 0 on ‘VuoControl’ (Operation cannot be accomplished in current state)e[0m
e[38;5;169m# pid=17505 t= 10.5158s VuoTelemetry.c:253 vuoSend() The connection between the composition and runner failed when trying to send a message of type 0 on ‘VuoControl’ (Operation cannot be accomplished in current state)e[0m
e[38;5;167m# pid=17503 t= 11.6107s VuoRuntimeState.cc:446 killProcessAfterTimeout_block_invoke_2() Warning: Waited 10 seconds for the composition to cleanly shut down, but it’s still running. Now I’m force-quitting it.e[0m
e[38;5;169m# pid=17505 t= 10.5162s VuoRuntimeState.cc:446 killProcessAfterTimeout_block_invoke_2() Warning: Waited 10 seconds for the composition to cleanly shut down, but it’s still running. Now I’m force-quitting it.e[0m

0 Likes

#3

So I know from:
2021-06-26 03:52:45.53 [VDMX] Technomorph Debug Logging

Plug In Position:16
After Info:Bins
Before Info:Blend Control Surfaces and C Report Surfaces

That the crash happens when it tries to load my Blend Control Surfaces:

03%20Debug%20Log%20Info

So how to further diagnose…
What I did was then to reopen it in 8724.
Rename those trouble plugIns so that they are at the very end.
So I added a Y in front of them all

YBLEND_LG1
YBLEND_LG2
YBLEND_LG3

This moves them to the end.

Then I re-edit a few of the Debug Log PlugIns info.
And added a new Debug Log PlugIn at the end just before those
PlugIns load.

Make sure you still have a Debug Log PlugIn at the end so you know when its done.

-----------000009999999
Save that in 8724. Maybe Quit and reopen 8724 and check it again.
Save As.

Then try opening in 8727 and see how much further you can get.


It will take you a while.

Eventually I removed those plugIns and it opened fine in 8727. Not sure exactly why.

Hope It Helps

0 Likes