AWS Thinkbox Discussion Forums

Verbose logging with Nuke 14, Deadline and windows

Hi I’m having some strange errors with Nuke at the moment and it seems to be to do with not just one node somewhere but a combination of them. I was just wondering if there was some way to force Nuke to do more detailed logging? I have found the env variable FOUNDRY_LOG_LEVEL but it doesn’t do anything. I tried the performance monitor but it doesn’t seem to save out the file and in the log all it shows are times with no other information.

Here’s my log:

=======================================================
Error
=======================================================
Error: FailRenderException : ValueError: not found.
   at Deadline.Plugins.DeadlinePlugin.FailRender(String message) (Python.Runtime.PythonException)
  File "C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\plugins\66a26b987b21d9f2906fc945\Nuke.py", line 264, in RenderTasks
    self.WritePython(scriptContents)
  File "C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\plugins\66a26b987b21d9f2906fc945\Nuke.py", line 41, in WritePython
    self.WaitForProcess()
  File "C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\plugins\66a26b987b21d9f2906fc945\Nuke.py", line 48, in WaitForProcess
    self.FlushMonitoredManagedProcessStdout( self.ProcessName )
  File "C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\plugins\66a26b987b21d9f2906fc945\Nuke.py", line 623, in HandleError
    self.deadlinePlugin.FailRender( self.GetRegexMatch( 0 ) )
   at Python.Runtime.Dispatcher.Dispatch(ArrayList args)
   at __FranticX_GenericDelegate0Dispatcher.Invoke()
   at Deadline.Plugins.DeadlinePlugin.RenderTasks()
   at Deadline.Plugins.DeadlinePlugin.DoRenderTasks()
   at Deadline.Plugins.PluginWrapper.RenderTasks(Task task, String& outMessage, AbortLevel& abortLevel)
   at Deadline.Plugins.PluginWrapper.RenderTasks(Task task, String& outMessage, AbortLevel& abortLevel)

=======================================================
Type
=======================================================
RenderPluginException

=======================================================
Stack Trace
=======================================================
   at Deadline.Plugins.SandboxedPlugin.d(DeadlineMessage bgq, CancellationToken bgr)
   at Deadline.Plugins.SandboxedPlugin.RenderTask(Task task, CancellationToken cancellationToken)
   at Deadline.Slaves.SlaveRenderThread.c(TaskLogWriter ajv, CancellationToken ajw)

=======================================================
Log
=======================================================
2024-07-25 16:17:21:  0: Loading Job's Plugin timeout is Disabled
2024-07-25 16:17:21:  0: SandboxedPlugin: Render Job As User disabled, running as current user 'User'
2024-07-25 16:17:22:  0: Executing plugin command of type 'Initialize Plugin'
2024-07-25 16:17:22:  0: INFO: Executing plugin script 'C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\plugins\66a26b987b21d9f2906fc945\Nuke.py'
2024-07-25 16:17:22:  0: INFO: Plugin execution sandbox using Python version 3
2024-07-25 16:17:22:  0: INFO: About: Nuke Plugin for Deadline
2024-07-25 16:17:22:  0: INFO: The job's environment will be merged with the current environment before rendering
2024-07-25 16:17:22:  0: Done executing plugin command of type 'Initialize Plugin'
2024-07-25 16:17:23:  0: Start Job timeout is disabled.
2024-07-25 16:17:23:  0: Task timeout is 599940 seconds (Regular Task Timeout)
2024-07-25 16:17:23:  0: Loaded job: sc0230-sh010_main_v0017 (66a26b987b21d9f2906fc945)
2024-07-25 16:17:23:  0: Executing plugin command of type 'Start Job'
2024-07-25 16:17:23:  0: DEBUG: S3BackedCache Client is not installed.
2024-07-25 16:17:23:  0: INFO: Executing global asset transfer preload script 'C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\plugins\66a26b987b21d9f2906fc945\GlobalAssetTransferPreLoad.py'
2024-07-25 16:17:23:  0: INFO: Looking for legacy (pre-10.0.26) AWS Portal File Transfer...
2024-07-25 16:17:23:  0: INFO: Looking for legacy (pre-10.0.26) File Transfer controller in C:/Program Files/Thinkbox/S3BackedCache/bin/task.py...
2024-07-25 16:17:23:  0: INFO: Could not find legacy (pre-10.0.26) AWS Portal File Transfer.
2024-07-25 16:17:23:  0: INFO: Legacy (pre-10.0.26) AWS Portal File Transfer is not installed on the system.
2024-07-25 16:17:23:  0: INFO: Prepping OFX cache
2024-07-25 16:17:23:  0: INFO: Checking Nuke temp path: C:\Users\User\AppData\Local\Temp\nuke
2024-07-25 16:17:23:  0: INFO: Path already exists
2024-07-25 16:17:23:  0: INFO: OFX cache prepped
2024-07-25 16:17:23:  0: INFO: Starting monitored managed process Nuke
2024-07-25 16:17:23:  0: INFO: Enable Path Mapping: True
2024-07-25 16:17:23:  0: INFO: Stdout Redirection Enabled: True
2024-07-25 16:17:23:  0: INFO: Stdout Handling Enabled: True
2024-07-25 16:17:23:  0: INFO: Popup Handling Enabled: True
2024-07-25 16:17:23:  0: INFO: QT Popup Handling Enabled: False
2024-07-25 16:17:23:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2024-07-25 16:17:23:  0: INFO: Using Process Tree: True
2024-07-25 16:17:23:  0: INFO: Hiding DOS Window: True
2024-07-25 16:17:23:  0: INFO: Creating New Console: False
2024-07-25 16:17:23:  0: INFO: Running as user: User
2024-07-25 16:17:23:  0: INFO: Executable: "C:\Program Files\Nuke14.0v4\Nuke14.0.exe"
2024-07-25 16:17:23:  0: INFO: Setting Process Environment Variable EDDY_DEVICE_LIST to 
2024-07-25 16:17:23:  0: INFO: Argument: -V 2 -t "C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\jobsData\66a26b987b21d9f2906fc945\thread0_tempOCMtn0\sc0230-sh010_Compositing_v0009.nk"
2024-07-25 16:17:23:  0: INFO: Full Command: "C:\Program Files\Nuke14.0v4\Nuke14.0.exe" -V 2 -t "C:\ProgramData\Thinkbox\Deadline10\workers\SonoxG4-Nuke-001\jobsData\66a26b987b21d9f2906fc945\thread0_tempOCMtn0\sc0230-sh010_Compositing_v0009.nk"
2024-07-25 16:17:23:  0: INFO: Startup Directory: "C:\Program Files\Nuke14.0v4"
2024-07-25 16:17:23:  0: INFO: Process Priority: BelowNormal
2024-07-25 16:17:23:  0: INFO: Process Affinity: default
2024-07-25 16:17:23:  0: INFO: Process is now running
2024-07-25 16:17:23:  0: Done executing plugin command of type 'Start Job'
2024-07-25 16:17:23:  0: Plugin rendering frame(s): 1018
2024-07-25 16:17:23:  0: Executing plugin command of type 'Render Task'
2024-07-25 16:17:23:  0: INFO: Rendering write node WritePrism2.WritePrismBase
2024-07-25 16:17:23:  0: STDOUT: Nuke 14.0v4, 64 bit, built Apr  3 2023.
2024-07-25 16:17:23:  0: STDOUT: Copyright (c) 2023 The Foundry Visionmongers Ltd.  All Rights Reserved.
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/arriSDKLoader.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/_pathsetup.py
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/init.tcl
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/init.py
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/setenv.tcl
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/formats.tcl
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/caravr/init.py
2024-07-25 16:17:24:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/init.py
2024-07-25 16:17:24:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/KM_RVM_Nuke/init.py
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/getenv.tcl
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/OCIOColorSpace.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/exrReader.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Grade.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Saturation.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Merge2.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/jpgReader.tcl
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/jpegReader.dll
2024-07-25 16:17:24:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/Cryptomatte.gizmo
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Unpremult.tcl
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Premult.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Remove.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Copy.dll
2024-07-25 16:17:24:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/Edge_Expand.gizmo
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Erode.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/ChannelMerge.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Invert.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Multiply.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/IDistort.dll
2024-07-25 16:17:24:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Clamp.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/FilterErode.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/CopyBBox.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Colorspace.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Keymix.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Shuffle2.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Constant.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Noise.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/caravr/C_STMap2_1.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Crop.dll
2024-07-25 16:17:25:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/Fix_Inf_Nan.gizmo
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/VectorGenerator.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/VectorBlur2.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/pngReader.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Reformat.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Transform.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/CopyMetaData.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Dilate.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Bokeh.dll
2024-07-25 16:17:25:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/NoiseAdvanced.gizmo
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/AdjBBox.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/ZDefocus2.dll
2024-07-25 16:17:25:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/ExpressionWaveGenerator.gizmo
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/DeepRead.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/exrReaderDeep.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/DeepRecolor.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/DeepToImage2.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Camera3.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/abcSceneReader.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/hdrReader.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Tile.dll
2024-07-25 16:17:25:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/Glow_Exponential.gizmo
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Keyer.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Gamma.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Add.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/ColorCorrect.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Sphere.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Scene.tcl
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/MergeGeo.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/ScanlineRender.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/HueShift.dll
2024-07-25 16:17:25:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/STMap.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Defocus.dll
2024-07-25 16:17:26:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/FireflyKiller.gizmo
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Difference.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Median.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/ProgramData/Prism2/plugins/Nuke/Gizmos/WritePrism.gizmo
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/exrWriter.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/BlinkScript.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/TimeBlur.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Axis2.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Card2.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Cube.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/PositionToPoints2.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Dissolve.dll
2024-07-25 16:17:26:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/GammaPlus.gizmo
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Glint.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/BlackOutside.dll
2024-07-25 16:17:26:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/Lightwrap_Exponential.gizmo
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/FFT.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/FFTMultiply.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/InvFFT.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/ColorMatrix.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/EdgeDetectWrapper.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/EdgeDetect.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Fill.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Convolve2.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Blend.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/VolumeRays.gizmo
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Radial.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Rectangle.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Merge.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/GodRays.dll
2024-07-25 16:17:26:  0: STDOUT: Loading D:/Users/Knowlese/Documents/.nuke/fl_VirtualLens.gizmo
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Log2Lin.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Sharpen.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/Ramp.dll
2024-07-25 16:17:26:  0: STDOUT: Loading C:/Program Files/Nuke14.0v4/plugins/ColorLookup.dll
2024-07-25 16:17:26:  0: STDOUT: [16:17:26 GMT Summer Time] Read nuke script: C:/ProgramData/Thinkbox/Deadline10/workers/SonoxG4-Nuke-001/jobsData/66a26b987b21d9f2906fc945/thread0_tempOCMtn0/sc0230-sh010_Compositing_v0009.nk
2024-07-25 16:17:26:  0: STDOUT: Traceback (most recent call last):
2024-07-25 16:17:26:  0: STDOUT:   File "<string>", line 5, in <module>
2024-07-25 16:17:26:  0: Done executing plugin command of type 'Render Task'

Hi.

AFAIK, those env vars are for Nuke Studio, not Nuke. And FOUNDRY_LOG_FILE needs to have FOUNDRY_LOG_LEVEL set as well :
e.g.

export FOUNDRY_LOG_LEVEL=verbose
export FOUNDRY_LOG_FILE=/tmp/nukestudio.log

Deadline already puts a verbose flag on the render “-V 2” so I don’t know if you’re going to get any more verbosity.

If you run it on the commandline, (w/o using deadline) does it output the same error: (File "<string>", line 5, in <module>)

1 Like
Privacy | Site terms | Cookie preferences