AWS Thinkbox Discussion Forums

Houdini 19.5.569, Renderman25.1, DL 10.2.1.1, network render client: Houdini.py”, line 410, in HandleStdoutError

Deadline
#1

I work with Houdini 19.5.569 and with Renderman 25.1, Deadline 10.2.1.1.
and there’s 75 machines in farm, some of them report the error below，every machine have the same version of both houdini and renderman.

=======================================================
Error
=======================================================
FailRenderException : Error: Expecting value: line 1 column 1 (char 0)
   at Deadline.Plugins.DeadlinePlugin.FailRender(String message) (Python.Runtime.PythonException)
  File "C:\ProgramData\Thinkbox\Deadline10\workers\R19012\plugins\6496c14b7227fb1fa70b3dc9\Houdini.py", line 410, 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 dl, Boolean dm)
   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 bgo, CancellationToken bgp)
   at Deadline.Plugins.SandboxedPlugin.RenderTask(Task task, CancellationToken cancellationToken)
   at Deadline.Slaves.SlaveRenderThread.c(TaskLogWriter ajt, CancellationToken aju)

=======================================================
Log
=======================================================
2023-06-24 20:34:48:  0: Loading Job's Plugin timeout is Disabled
2023-06-24 20:34:48:  0: SandboxedPlugin: Render Job As User disabled, running as current user 'render'
2023-06-24 20:34:50:  0: Executing plugin command of type 'Initialize Plugin'
2023-06-24 20:34:50:  0: INFO: Executing plugin script 'C:\ProgramData\Thinkbox\Deadline10\workers\R19012\plugins\6496c14b7227fb1fa70b3dc9\Houdini.py'
2023-06-24 20:34:50:  0: INFO: Plugin execution sandbox using Python version 3
2023-06-24 20:34:50:  0: INFO: About: Houdini Plugin for Deadline
2023-06-24 20:34:50:  0: INFO: The job's environment will be merged with the current environment before rendering
2023-06-24 20:34:50:  0: Done executing plugin command of type 'Initialize Plugin'
2023-06-24 20:34:50:  0: Start Job timeout is disabled.
2023-06-24 20:34:50:  0: Task timeout is disabled.
2023-06-24 20:34:50:  0: Loaded job: yj_r1_00650_lgt_0630_jiazi_v002_qr (6496c14b7227fb1fa70b3dc9)
2023-06-24 20:34:50:  0: Executing plugin command of type 'Start Job'
2023-06-24 20:34:50:  0: DEBUG: S3BackedCache Client is not installed.
2023-06-24 20:34:50:  0: INFO: Executing global asset transfer preload script 'C:\ProgramData\Thinkbox\Deadline10\workers\R19012\plugins\6496c14b7227fb1fa70b3dc9\GlobalAssetTransferPreLoad.py'
2023-06-24 20:34:50:  0: INFO: Looking for legacy (pre-10.0.26) AWS Portal File Transfer...
2023-06-24 20:34:50:  0: INFO: Looking for legacy (pre-10.0.26) File Transfer controller in C:/Program Files/Thinkbox/S3BackedCache/bin/task.py...
2023-06-24 20:34:50:  0: INFO: Could not find legacy (pre-10.0.26) AWS Portal File Transfer.
2023-06-24 20:34:50:  0: INFO: Legacy (pre-10.0.26) AWS Portal File Transfer is not installed on the system.
2023-06-24 20:34:50:  0: Done executing plugin command of type 'Start Job'
2023-06-24 20:34:50:  0: Plugin rendering frame(s): 1012
2023-06-24 20:34:50:  0: Executing plugin command of type 'Render Task'
2023-06-24 20:34:50:  0: INFO: Starting Houdini Job
2023-06-24 20:34:50:  0: INFO: Stdout Redirection Enabled: True
2023-06-24 20:34:50:  0: INFO: Stdout Handling Enabled: True
2023-06-24 20:34:50:  0: INFO: Popup Handling Enabled: True
2023-06-24 20:34:50:  0: INFO: QT Popup Handling Enabled: False
2023-06-24 20:34:50:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2023-06-24 20:34:50:  0: INFO: Using Process Tree: True
2023-06-24 20:34:50:  0: INFO: Hiding DOS Window: True
2023-06-24 20:34:50:  0: INFO: Creating New Console: False
2023-06-24 20:34:50:  0: INFO: Running as user: render
2023-06-24 20:34:50:  0: INFO: Executable: "C:\Program Files\Side Effects Software\Houdini 19.5.569\bin\hython3.9.exe"
2023-06-24 20:34:50:  0: INFO: Argument: "C:\ProgramData\Thinkbox\Deadline10\workers\R19012\plugins\6496c14b7227fb1fa70b3dc9\hrender_dl.py" -f 1012 1012 1 -d /stage/jiazi -tempdir "C:\ProgramData\Thinkbox\Deadline10\workers\R19012\jobsData\6496c14b7227fb1fa70b3dc9\0_tempHzqRA0" -arnoldAbortOnLicenseFail 1 "X:/yuanjun_yj/r1/00650/lgt_0630/V002/yj_r1_00650_lgt_0630_jiazi_v002_qr.hip"
2023-06-24 20:34:50:  0: INFO: Full Command: "C:\Program Files\Side Effects Software\Houdini 19.5.569\bin\hython3.9.exe" "C:\ProgramData\Thinkbox\Deadline10\workers\R19012\plugins\6496c14b7227fb1fa70b3dc9\hrender_dl.py" -f 1012 1012 1 -d /stage/jiazi -tempdir "C:\ProgramData\Thinkbox\Deadline10\workers\R19012\jobsData\6496c14b7227fb1fa70b3dc9\0_tempHzqRA0" -arnoldAbortOnLicenseFail 1 "X:/yuanjun_yj/r1/00650/lgt_0630/V002/yj_r1_00650_lgt_0630_jiazi_v002_qr.hip"
2023-06-24 20:34:50:  0: INFO: Startup Directory: "C:\Program Files\Side Effects Software\Houdini 19.5.569\bin"
2023-06-24 20:34:50:  0: INFO: Process Priority: BelowNormal
2023-06-24 20:34:50:  0: INFO: Process Affinity: default
2023-06-24 20:34:50:  0: INFO: Process is now running
2023-06-24 20:34:51:  0: STDOUT: Error running pythonrc.py:
2023-06-24 20:34:51:  0: STDOUT: Traceback (most recent call last):
2023-06-24 20:34:51:  0: STDOUT:   File "C:/Program Files/Pixar/RenderManForHoudini-25.1/3.9/19.5.569/python3.9libs/pythonrc.py", line 63, in <module>
2023-06-24 20:34:51:  0: STDOUT:     rfh.prefs.init_prefs()
2023-06-24 20:34:51:  0: STDOUT:   File "C:\Program Files/Pixar/RenderManForHoudini-25.1/3.9/19.5.569/python3.9libs\rfh\prefs.py", line 345, in init_prefs
2023-06-24 20:34:51:  0: STDOUT:     read()
2023-06-24 20:34:51:  0: STDOUT:   File "C:\Program Files/Pixar/RenderManForHoudini-25.1/3.9/19.5.569/python3.9libs\rfh\prefs.py", line 383, in read
2023-06-24 20:34:51:  0: STDOUT:     prefs_dict = json.loads(s)
2023-06-24 20:34:51:  0: STDOUT:   File "C:\PROGRA~1\SIDEEF~1\HOUDIN~1.569\python39\lib\json\__init__.py", line 346, in loads
2023-06-24 20:34:51:  0: STDOUT:     return _default_decoder.decode(s)
2023-06-24 20:34:51:  0: STDOUT:   File "C:\PROGRA~1\SIDEEF~1\HOUDIN~1.569\python39\lib\json\decoder.py", line 337, in decode
2023-06-24 20:34:51:  0: STDOUT:     obj, end = self.raw_decode(s, idx=_w(s, 0).end())
2023-06-24 20:34:51:  0: STDOUT:   File "C:\PROGRA~1\SIDEEF~1\HOUDIN~1.569\python39\lib\json\decoder.py", line 355, in raw_decode
2023-06-24 20:34:51:  0: STDOUT:     raise JSONDecodeError("Expecting value", s, err.value) from None
2023-06-24 20:34:51:  0: Done executing plugin command of type 'Render Task'

=======================================================
Details
=======================================================
Date: 06/24/2023 20:34:55
Frames: 1012
Elapsed Time: 00:00:00:07
Job Submit Date: 06/24/2023 18:11:23
Job User: qinrui
Average RAM Usage: 4046630912 (6%)
Peak RAM Usage: 4046630912 (6%)
Average CPU Usage: 8%
Peak CPU Usage: 20%
Used CPU Clocks (x10^6 cycles): 12804
Total CPU Clocks (x10^6 cycles): 160047

=======================================================
Worker Information
=======================================================
Worker Name: R19012
Version: v10.2.0.10 Release (3b87216c7)
Operating System: Windows 10
Running As Service: No
Machine User: render
IP Address: 192.168.1.165
MAC Address: 0C:9D:92:10:EA:19
CPU Architecture: x64
CPUs: 12
CPU Usage: 4%
Memory Usage: 3.8 GB / 63.9 GB (5%)
Free Disk Space: 842.550 GB 
Video Card: NVIDIA GeForce GT 710
#2

Hello @wanghongshi

Thanks for reaching out, looking at the error provided it seems like an issue with running the Python script pythonrc.py during the execution of Houdini render process and printed as STDOUT as it is coming from Houdini, please follow the steps outlined in Troubleshooting render Issues in Houdini doc to isolate the issue outside of Deadline and check it if fails the rendering with same error.

Also, please share the Task report of a Job that rendered the same scene successfully by other render nodes along with the worker logs of both render nodes that failed to render and the node that successfully rendered for comparison purposes!

Privacy | Site terms | Cookie preferences