Houdini Deadline render error

Hi there!
Anybody with idea what is going on?
I update Deadline to 10.1.18.4 Release, use Houdini 18.5.596 and Redshift 3.0.49.
After updating Deadline stop to render.
I will appreciate any idea how to solve this issue.
Thanks!

Here is my job report:

Error

FailRenderException : Error: Missing parentheses in call to ‘print’. Did you mean print(“Appending “” + path + “” to system path to import SubmitDeadlineRop module”)?
at Deadline.Plugins.DeadlinePlugin.FailRender(String message) (Python.Runtime.PythonException)
File “C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\plugins\6149e7fa12d0e24754c91ec2\Houdini.py”, line 427, in HandleStdoutError
self.FailRender(self.GetRegexMatch(1))
at Python.Runtime.Dispatcher.Dispatch(ArrayList args)
at __FranticX_Processes_ManagedProcess_StdoutHandlerDelegateDispatcher.Invoke()
at FranticX.Processes.ManagedProcess.RegexHandlerCallback.CallFunction()
at FranticX.Processes.ManagedProcess.e(String di, Boolean dj)
at FranticX.Processes.ManagedProcess.Execute(Boolean waitForExit)
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 bfi, CancellationToken bfj)
at Deadline.Plugins.SandboxedPlugin.RenderTask(Task task, CancellationToken cancellationToken)
at Deadline.Slaves.SlaveRenderThread.c(TaskLogWriter ajm, CancellationToken ajn)

=======================================================
Log

2021-09-21 16:26:13: 0: Loading Job’s Plugin timeout is Disabled
2021-09-21 16:26:13: 0: WARNING: Python version for ‘Houdini’ plugin is not specified! Defaulting to Py2.
2021-09-21 16:26:13: 0: SandboxedPlugin: Render Job As User disabled, running as current user ‘predrag’
2021-09-21 16:26:15: 0: nogui was not added to the CommandLineParser.
2021-09-21 16:26:15: 0: Executing plugin command of type ‘Initialize Plugin’
2021-09-21 16:26:16: 0: INFO: Executing plugin script ‘C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\plugins\6149e7fa12d0e24754c91ec2\Houdini.py’
2021-09-21 16:26:16: 0: INFO: About: Houdini Plugin for Deadline
2021-09-21 16:26:16: 0: INFO: The job’s environment will be merged with the current environment before rendering
2021-09-21 16:26:16: 0: Done executing plugin command of type ‘Initialize Plugin’
2021-09-21 16:26:16: 0: Start Job timeout is disabled.
2021-09-21 16:26:16: 0: Task timeout is disabled.
2021-09-21 16:26:16: 0: Loaded job: houdini_test (6149e7fa12d0e24754c91ec2)
2021-09-21 16:26:16: 0: Executing plugin command of type ‘Start Job’
2021-09-21 16:26:16: 0: DEBUG: S3BackedCache Client is not installed.
2021-09-21 16:26:16: 0: INFO: Executing global asset transfer preload script ‘C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\plugins\6149e7fa12d0e24754c91ec2\GlobalAssetTransferPreLoad.py’
2021-09-21 16:26:16: 0: INFO: Looking for legacy (pre-10.0.26) AWS Portal File Transfer…
2021-09-21 16:26:16: 0: INFO: Looking for legacy (pre-10.0.26) File Transfer controller in C:/Program Files/Thinkbox/S3BackedCache/bin/task.py…
2021-09-21 16:26:16: 0: INFO: Could not find legacy (pre-10.0.26) AWS Portal File Transfer.
2021-09-21 16:26:16: 0: INFO: Legacy (pre-10.0.26) AWS Portal File Transfer is not installed on the system.
2021-09-21 16:26:16: 0: Done executing plugin command of type ‘Start Job’
2021-09-21 16:26:16: 0: Plugin rendering frame(s): 17-18
2021-09-21 16:26:16: 0: Executing plugin command of type ‘Render Task’
2021-09-21 16:26:16: 0: INFO: Starting Houdini Job
2021-09-21 16:26:16: 0: INFO: Stdout Redirection Enabled: True
2021-09-21 16:26:16: 0: INFO: Stdout Handling Enabled: True
2021-09-21 16:26:16: 0: INFO: Popup Handling Enabled: True
2021-09-21 16:26:16: 0: INFO: QT Popup Handling Enabled: False
2021-09-21 16:26:16: 0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2021-09-21 16:26:16: 0: INFO: Using Process Tree: True
2021-09-21 16:26:16: 0: INFO: Hiding DOS Window: True
2021-09-21 16:26:16: 0: INFO: Creating New Console: False
2021-09-21 16:26:16: 0: INFO: Running as user: predrag
2021-09-21 16:26:16: 0: INFO: Executable: “C:\Program Files\Side Effects Software\Houdini 18.5.596\bin\Hython.exe”
2021-09-21 16:26:16: 0: INFO: Argument: “C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\plugins\6149e7fa12d0e24754c91ec2\hrender_dl.py” -f 17 18 1 -o “H:/T4_ROGASKA/images/out_cg/0921/scatter_07_vdb_gravity.$F4.exr” -g -d /out/Redshift_ROP1 -tempdir “C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\jobsData\6149e7fa12d0e24754c91ec2\0_temp7NmsK0” -arnoldAbortOnLicenseFail 1 “H:/T4_ROGASKA/houdini/scripts/scatter_07_vdb_gravity.hip”
2021-09-21 16:26:16: 0: INFO: Full Command: “C:\Program Files\Side Effects Software\Houdini 18.5.596\bin\Hython.exe” “C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\plugins\6149e7fa12d0e24754c91ec2\hrender_dl.py” -f 17 18 1 -o “H:/T4_ROGASKA/images/out_cg/0921/scatter_07_vdb_gravity.$F4.exr” -g -d /out/Redshift_ROP1 -tempdir “C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\jobsData\6149e7fa12d0e24754c91ec2\0_temp7NmsK0” -arnoldAbortOnLicenseFail 1 “H:/T4_ROGASKA/houdini/scripts/scatter_07_vdb_gravity.hip”
2021-09-21 16:26:16: 0: INFO: Startup Directory: “C:\Program Files\Side Effects Software\Houdini 18.5.596\bin”
2021-09-21 16:26:16: 0: INFO: Process Priority: BelowNormal
2021-09-21 16:26:16: 0: INFO: Process Affinity: default
2021-09-21 16:26:16: 0: INFO: Process is now running
2021-09-21 16:26:17: 0: STDOUT: 00:00:00 305MB | log started Tue Sep 21 16:26:17 2021
2021-09-21 16:26:17: 0: STDOUT: 00:00:00 305MB | Arnold 6.2.1.1 [fbf3b21a] windows clang-10.0.1 oiio-2.3.2 osl-1.12.0 vdb-7.1.1 clm-2.0.0.235 rlm-14.1.3 optix-6.6.0 2021/06/04 18:35:03
2021-09-21 16:26:17: 0: STDOUT: 00:00:00 305MB | running on ChuckNorris, pid=16044
2021-09-21 16:26:17: 0: STDOUT: 00:00:00 305MB | 1 x Intel(R) Core™ i7-5960X CPU @ 3.00GHz (8 cores, 16 logical) with 65360MB
2021-09-21 16:26:17: 0: STDOUT: 00:00:00 305MB | NVIDIA driver version 466.27 (Optix 60800)
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | GPU 0: NVIDIA GeForce RTX 2080 @ 1845MHz (compute 7.5) with 8192MB (7611MB available) (NVLink:0)
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | GPU 1: NVIDIA GeForce GTX 1080 Ti @ 1607MHz (compute 6.1) with 11264MB (11014MB available) (NVLink:0)
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | Windows 10 (version 10.0, build 19042)
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | soft limit for open files raised from 8192 to 2048
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB |
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | [htoa_op] Begin registration …
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | loading plugins from C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/plugins …
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | alembic_proc.dll: alembic uses Arnold 6.2.1.1
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | cryptomatte.dll: cryptomatte uses Arnold 6.2.1.1
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | cryptomatte.dll: cryptomatte_filter uses Arnold 6.2.1.1
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | cryptomatte.dll: cryptomatte_manifest_driver uses Arnold 6.2.1.1
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 305MB | htoa_mandelbulb_proc.dll: mandelbulb uses Arnold 6.2.1.1
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | usd_proc.dll: usd uses Arnold 6.2.1.1
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | loaded 6 plugins from 4 lib(s) in 0:00.09
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/cameras.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/imagers.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/lights.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/operators.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 314MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/options.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 315MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/shaders.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 316MB | [htoa_op] Loading metadata file: C:/Users/predrag/htoa/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/htoa-5.6.3.0_ra766b1f_houdini-18.5.596.py3/arnold/metadata/shapes.mtd
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 317MB | [obj_procedurals] Registering procedurals …
2021-09-21 16:26:18: 0: STDOUT: 00:00:00 317MB | [obj_procedurals] procedural registration done.
2021-09-21 16:26:20: 0: STDOUT: [2021/Sep/21|16:26:20] VFH | Info| * Build hash:fb365cd from 16 Jun 2021 11:46
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Redshift for Houdini plugin version 3.0.49 (Jun 28 2021 16:43:39)
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Plugin compile time HDK version: 18.5.596
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Houdini host version: 18.5.596
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Plugin dso/dll and config path: C:/ProgramData/Redshift/Plugins/Houdini/18.5.596/dso
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Core data path: C:\ProgramData\Redshift
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Local data path: C:\ProgramData\Redshift
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Procedurals path: C:\ProgramData\Redshift\Procedurals
2021-09-21 16:26:20: 0: STDOUT: [Redshift] Preferences file path: C:\ProgramData\Redshift\preferences.xml
2021-09-21 16:26:20: 0: STDOUT: [Redshift] License path: C:\ProgramData\Redshift
2021-09-21 16:26:20: 0: STDOUT: 00:00:02 365MB | [rop_operators] Registering operators …
2021-09-21 16:26:20: 0: STDOUT: 00:00:02 365MB | [rop_operators] operator registration done.
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 423MB | [vop_shaders] Registering shaders …
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 425MB | [vop_shaders] shader registration done.
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 425MB | [vop_imagers] Registering imagers …
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 426MB | [vop_imagers] imager registration done.
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 426MB | [htoa_op] End registration.
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 426MB |
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 426MB | releasing resources
2021-09-21 16:26:22: 0: STDOUT: 00:00:04 423MB | Arnold shutdown
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | log started Tue Sep 21 16:26:23 2021
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | Arnold 6.2.1.1 [fbf3b21a] windows clang-10.0.1 oiio-2.3.2 osl-1.12.0 vdb-7.1.1 clm-2.0.0.235 rlm-14.1.3 optix-6.6.0 2021/06/04 18:35:03
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | running on ChuckNorris, pid=16044
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | 1 x Intel(R) Core™ i7-5960X CPU @ 3.00GHz (8 cores, 16 logical) with 65360MB
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | NVIDIA driver version 466.27 (Optix 60800)
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | GPU 0: NVIDIA GeForce RTX 2080 @ 1845MHz (compute 7.5) with 8192MB (7611MB available) (NVLink:0)
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | GPU 1: NVIDIA GeForce GTX 1080 Ti @ 1607MHz (compute 6.1) with 11264MB (11014MB available) (NVLink:0)
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | Windows 10 (version 10.0, build 19042)
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB | soft limit for open files is set at 2048
2021-09-21 16:26:23: 0: STDOUT: 00:00:00 442MB |
2021-09-21 16:26:29: 0: STDOUT: Detected Houdini version: (18, 5, 596)
2021-09-21 16:26:29: 0: STDOUT: [‘C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\plugins\6149e7fa12d0e24754c91ec2\hrender_dl.py’, ‘-f’, ‘17’, ‘18’, ‘1’, ‘-o’, ‘H:/T4_ROGASKA/images/out_cg/0921/scatter_07_vdb_gravity.$F4.exr’, ‘-g’, ‘-d’, ‘/out/Redshift_ROP1’, ‘-tempdir’, ‘C:\ProgramData\Thinkbox\Deadline10\workers\ChuckNorris\jobsData\6149e7fa12d0e24754c91ec2\0_temp7NmsK0’, ‘-arnoldAbortOnLicenseFail’, ‘1’, ‘H:/T4_ROGASKA/houdini/scripts/scatter_07_vdb_gravity.hip’]
2021-09-21 16:26:29: 0: STDOUT: Start: 17
2021-09-21 16:26:29: 0: STDOUT: End: 18
2021-09-21 16:26:29: 0: STDOUT: Increment: 1
2021-09-21 16:26:29: 0: STDOUT: Ignore Inputs: True
2021-09-21 16:26:29: 0: STDOUT: Output: H:/T4_ROGASKA/images/out_cg/0921/scatter_07_vdb_gravity.$F4.exr
2021-09-21 16:26:29: 0: STDOUT: Driver: /out/Redshift_ROP1
2021-09-21 16:26:29: 0: STDOUT: Input File: H:/T4_ROGASKA/houdini/scripts/scatter_07_vdb_gravity.hip
2021-09-21 16:26:30: 0: STDOUT: [Redshift] New OCIO config ‘C:\ProgramData\Redshift\Data\OCIO\config.ocio’ path fond. Loading it!
2021-09-21 16:26:30: 0: STDOUT: Error running event handler:
2021-09-21 16:26:30: 0: STDOUT: File “Driver/deadline, PythonModule”, line 19
2021-09-21 16:26:30: 0: STDOUT: print “Appending “” + path + “” to system path to import SubmitDeadlineRop module”
2021-09-21 16:26:30: 0: STDOUT: ^
2021-09-21 16:26:30: 0: Done executing plugin command of type ‘Render Task’

=======================================================
Details

Date: 09/21/2021 16:26:33
Frames: 17-18
Elapsed Time: 00:00:00:21
Job Submit Date: 09/21/2021 16:11:06
Job User: predrag
Average RAM Usage: 9298781184 (14%)
Peak RAM Usage: 9769271296 (15%)
Average CPU Usage: 8%
Peak CPU Usage: 15%
Used CPU Clocks (x10^6 cycles): 61744
Total CPU Clocks (x10^6 cycles): 771798

=======================================================
Worker Information

Worker Name: ChuckNorris
Version: v10.1.18.4 Release (c8ded2b7a)
Operating System: Windows 10 Pro
Running As Service: No
Machine User: predrag
IP Address: fe80::50ab:dfd2:367b:8552%13
MAC Address: 00:05:9A:3C:7A:00
CPU Architecture: x64
CPUs: 16
CPU Usage: 4%
Memory Usage: 8.6 GB / 63.8 GB (13%)
Free Disk Space: 10.318 TB (57.698 GB on C:, 9.078 TB on D:, 1.183 TB on H:)
Video Card: NVIDIA GeForce GTX 1080 Ti

That means somewhere in your integrations there is still code for Python 2, that needs to be updated.
Python 2 (long time deprecated syntax): print "bla"
Python 3: print("bla")

How do you track down the integration past what I have already done?

I updated my repo to 10.1.20.3 and houdini 19.561 updated the submitter using the installer in the repo. I get this error now. I also changed the python version to 3 in repo config and checked the houdini.py and it has the python3 tag at the top.

The problem is in your Deadline ROP. You have to reinstall the Deadline submitter for Houdini on the worker.
If it still doesn’t work, then it is broken. You can fix it by opening Type Properties on the Deadline node → Scripts → Python Module.

Manual install seemed to work. The installers were not working to fix the ROP.

1 Like