Rendering in 3ds Max errors out: Unexpected command ...

loocas · July 29, 2012, 4:07pm

This is the error output I’m seeing in the monitor:

0: An exception occurred: An error occurred in StartJob(): ExecuteScript: Unexpected command “TempFolder” received. (Deadline.Plugins.RenderPluginException)

Any thoughts?

loocas · July 29, 2012, 4:08pm

Ou!

I just tried submitting (via the integrated SMTD script) the whole scene and I’m still getting this error!

0: An exception occurred: Exception during render: An error occurred in RenderTasks(): RenderFrame: Unexpected command “TempFolder” received.
at Deadline.Plugins.ScriptPlugin.RenderTasks(String taskId, Int32 startFrame, Int32 endFrame, String& outMessage) (Deadline.Plugins.RenderPluginException)

What is this?!

loocas · July 29, 2012, 4:13pm

This is the log:

2012-07-29 18:08:03: 0: An exception occurred: Exception during render: An error occurred in RenderTasks(): RenderFrame: Unexpected command "TempFolder" received. at Deadline.Plugins.ScriptPlugin.RenderTasks(String taskId, Int32 startFrame, Int32 endFrame, String& outMessage) (Deadline.Plugins.RenderPluginException) 2012-07-29 18:08:03: 0: Unloading plugin: 3dsmax 2012-07-29 18:08:04: Scheduler Thread - Render Thread 0 threw an error: 2012-07-29 18:08:04: Scheduler Thread - Exception during render: An error occurred in RenderTasks(): RenderFrame: Unexpected command "TempFolder" received. at Deadline.Plugins.ScriptPlugin.RenderTasks(String taskId, Int32 startFrame, Int32 endFrame, String& outMessage) 2012-07-29 18:08:05: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> 2012-07-29 18:08:05: Exception Details RenderPluginException -- Exception during render: An error occurred in RenderTasks(): RenderFrame: Unexpected command "TempFolder" received. at Deadline.Plugins.ScriptPlugin.RenderTasks(String taskId, Int32 startFrame, Int32 endFrame, String& outMessage) RenderPluginException.Cause: JobError (2) RenderPluginException.HasSlaveLog: True Exception.Data: ( ) Exception.TargetSite: Void RenderTask(System.String, Int32, Int32) Exception.Source: deadline Exception.StackTrace: at Deadline.Plugins.Plugin.RenderTask(String taskId, Int32 startFrame, Int32 endFrame) at Deadline.Slaves.SlaveRenderThread.RenderCurrentTask(TaskLogWriter tlw)

loocas · July 29, 2012, 4:16pm

[code]=======================================================
Error Type

FailRenderException

=======================================================
Error Stack Trace

at Deadline.Plugins.ScriptPlugin.FailRender(String message)
at CallSite.Target(Closure , CallSite , CodeContext , Object , Object )
at System.Dynamic.UpdateDelegates.UpdateAndExecute3[T0,T1,T2,TRet](CallSite site, T0 arg0, T1 arg1, T2 arg2)
at ExecuteScript$59(Closure , PythonFunction , Object , Object , Object , Object )
at IronPython.Runtime.PythonFunction.FunctionCaller4.Call4(CallSite site, CodeContext context, Object func, T0 arg0, T1 arg1, T2 arg2, T3 arg3) at CallSite.Target(Closure , CallSite , CodeContext , Object , Int32 , Object , Object ) at System.Dynamic.UpdateDelegates.UpdateAndExecute5[T0,T1,T2,T3,T4,TRet](CallSite site, T0 arg0, T1 arg1, T2 arg2, T3 arg3, T4 arg4) at StartMaxJob$57(Closure , PythonFunction , Object ) at IronPython.Compiler.PythonCallTargets.OriginalCallTarget1(PythonFunction function, Object arg0) at IronPython.Runtime.PythonFunction.FunctionCaller1.Call1(CallSite site, CodeContext context, Object func, T0 arg0)
at CallSite.Target(Closure , CallSite , CodeContext , Object )
at System.Dynamic.UpdateDelegates.UpdateAndExecute2[T0,T1,TRet](CallSite site, T0 arg0, T1 arg1)
at StartJob$36(Closure , PythonFunction , Object )
at IronPython.Compiler.PythonCallTargets.OriginalCallTarget1(PythonFunction function, Object arg0)
at IronPython.Runtime.PythonFunction.FunctionCaller`1.Call1(CallSite site, CodeContext context, Object func, T0 arg0)
at CallSite.Target(Closure , CallSite , CodeContext , Object )
at IronPython.NewTypes.Deadline.Plugins.DeadlinePlugin_1$1.StartJob()
at Deadline.Plugins.ScriptPlugin.StartJob(Job job)[/code]

loocas · July 29, 2012, 4:17pm

What the hell?!

It’s suddenly failing even when submitting via the Monitor.

What is this?!

Btw: Deadline version 5.2.0.47700 on Windows 7 x64 (Windows Server 2008 for the repo)

loocas · July 29, 2012, 8:08pm

I dug a bit deeper and the only mention of the command “TempFolder” is in the 3dsmaxConnect.log: 3dsmaxConnect.log (3.27 KB)

How/where can I fix this error? I need to render some stuff tomorrow and I’d hate to revert back to the older Deadline version. (5.2 didn’t throw this stuff at me in my virtual testing environment, that’s why I deployed it on my farm).

loocas · July 29, 2012, 8:21pm

Ok, I was able to identify the source of the errors.

It seems it’s connected to the 3dsmax.py in the 3dsmax plugin of Deadline.

I found this command on line 1379:

self.MaxSocket.Send( "TempFolder,\"" + GetSystemTempPath().replace( "\\", "/" ) )

When commenting it out, Deadline was throwing yet another MaxSocket related error:

line 1441:self.MaxSocket.Send( "CurrentTask,%s" % GetCurrentTaskId() )

Why is the socket send method failing? Or why is Deadline tripping over it?

loocas · July 29, 2012, 8:25pm

Also, this is in the job’s log:

0: INFO: Start Job called - starting up 3dsmax plugin 0: INFO: Rendering with 3dsmax version: 2011 0: INFO: Build of 3dsmax to force: 64bit 0: INFO: Rendering with executable: C:\Program Files\Autodesk\3ds Max 2011\3dsmax.exe 0: INFO: Checking registry for 3dsmax language code 0: INFO: Found language code: 409 0: INFO: Language code string: enu 0: INFO: Fail on existing 3dsmax process: 0 0: INFO: Load 3dsmax timeout: 1000 seconds 0: INFO: Start job timeout: 1000 seconds 0: INFO: Progress update timeout: 8000 seconds 0: INFO: Progress update timout disabled: 0 0: INFO: Slave mode enabled: 1 0: INFO: Silent mode enabled: 0 0: INFO: Local rendering enabled: 1 0: INFO: Strict 3dsmax check enabled: 1 0: INFO: Verifying installation of 3dsmax 2011 at C:\Program Files\Autodesk\3ds Max 2011 0: INFO: Version of core.dll: 13.1.0.114 0: INFO: Ensuring 3dsmax install is valid 0: INFO: Running 3dsmaxcmd workaround 0: INFO: 3dsmaxcmd.exe returned: P7/29/2012 22:15:40; Error opening scene file: "C:\Program Files\Autodesk\3ds Max 2011\" 0: INFO: 3dsmax start up file: C:\Users\loocas\AppData\Local\Thinkbox\Deadline\slave\Vfx01\plugins\deadlineStartupMax2011.max 0: INFO: Using user profiles: 0 0: INFO: 3dsmax data path: C:\Program Files\Autodesk\3ds Max 2011\ 0: INFO: 3dsmax ini file: C:\Program Files\Autodesk\3ds Max 2011\3dsmax.ini 0: INFO: Network log file: C:\Program Files\Autodesk\3ds Max 2011\Network\Max.log 0: INFO: Plugin ini file: \\RAMMSTEIN\Deadline\plugins\3dsmax\plugin_3dsMax2011fR.ini 0: INFO: Not including user profile plugin.ini because a custom plugin.ini is being used 0: INFO: Lightning connection plugin: C:\Users\loocas\AppData\Local\Thinkbox\Deadline\slave\Vfx01\plugins\lightning64Max2011.dlx 0: INFO: 3dsmax socket connection port: 52855 0: INFO: Creating startup script: C:\Users\loocas\AppData\Local\Temp\lightning_startup.ms 0: INFO: Copying C:\Users\loocas\AppData\Local\Thinkbox\Deadline\slave\Vfx01\plugins\lightning64Max2011.dlx to C:\Users\loocas\AppData\Local\Thinkbox\Deadline\temp\lightning\lightning.dlx 0: INFO: Copying dl.ini to 3dsmax data path: C:\Program Files\Autodesk\3ds Max 2011\ 0: INFO: If this fails, make sure that the necessary permissions are set on this folder to allow for this copy to take place 0: INFO: Starting monitored managed process 3dsmaxProcess

So, Max is listening on port 52855, so why are the commands still tripping Deadline?

loocas · July 29, 2012, 9:17pm

Ok, I can confirm that commenting out these two lines in the 3dsmax.py in the plugins directory of Deadline, gets rid of the error and the jobs render just fine:

1379: self.MaxSocket.Send( "TempFolder,\"" + GetSystemTempPath().replace( "\\", "/" ) )
1441: self.MaxSocket.Send( "CurrentTask,%s" % GetCurrentTaskId() )

3ds Max 2011 x64, Deadline 5.2.0.47700 on Windows 7 Pro x64

loocas · July 29, 2012, 9:38pm

Ok, not so fast.

When trying to submit the very same scene via the integrated SMTD, I got this error instead:

[code]ExecuteScript: Unexpected command “IgnoreRenderElementsByName” received.

=======================================================
Error Type

FailRenderException

=======================================================
Error Stack Trace

at Deadline.Plugins.ScriptPlugin.FailRender(String message)
at CallSite.Target(Closure , CallSite , CodeContext , Object , Object )
at System.Dynamic.UpdateDelegates.UpdateAndExecute3[T0,T1,T2,TRet](CallSite site, T0 arg0, T1 arg1, T2 arg2)
at ExecuteScript$564(Closure , PythonFunction , Object , Object , Object , Object )
at IronPython.Runtime.PythonFunction.FunctionCaller4.Call4(CallSite site, CodeContext context, Object func, T0 arg0, T1 arg1, T2 arg2, T3 arg3) at CallSite.Target(Closure , CallSite , CodeContext , Object , Int32 , Object , Object ) at System.Dynamic.UpdateDelegates.UpdateAndExecute5[T0,T1,T2,T3,T4,TRet](CallSite site, T0 arg0, T1 arg1, T2 arg2, T3 arg3, T4 arg4) at StartMaxJob$562(Closure , PythonFunction , Object ) at IronPython.Compiler.PythonCallTargets.OriginalCallTarget1(PythonFunction function, Object arg0) at IronPython.Runtime.PythonFunction.FunctionCaller1.Call1(CallSite site, CodeContext context, Object func, T0 arg0)
at CallSite.Target(Closure , CallSite , CodeContext , Object )
at System.Dynamic.UpdateDelegates.UpdateAndExecute2[T0,T1,TRet](CallSite site, T0 arg0, T1 arg1)
at StartJob$541(Closure , PythonFunction , Object )
at IronPython.Compiler.PythonCallTargets.OriginalCallTarget1(PythonFunction function, Object arg0)
at IronPython.Runtime.PythonFunction.FunctionCaller`1.Call1(CallSite site, CodeContext context, Object func, T0 arg0)
at CallSite.Target(Closure , CallSite , CodeContext , Object )
at IronPython.NewTypes.Deadline.Plugins.DeadlinePlugin_1$1.StartJob()
at Deadline.Plugins.ScriptPlugin.StartJob(Job job)[/code]

So, basically, deadline 5.2 is broken for me and I can’t use it for 3ds Max rendering.
Funnily I hadn’t bumped into such errors in my virtual environment prior to deployment.

rrussell · July 29, 2012, 11:23pm

It sounds like there is a version mismatch between the 3dsmax.py file in the 3dsmax plugin folder and the Lightning plugin it’s connecting to (Lightning is the plugin that 3dsmax loads, which facilitates the communication between Deadline and 3dsmax). A possible solution would be to run the 5.2 Repository installer again. If the 3dsmax plugin is broken, the installer should fix it.

Give that a try and let us know if it helps!

Cheers,

Ryan

loocas · July 30, 2012, 7:19am

Hi Ryan, thanks for your input.

Yeah, I thought that this might be the case (or something along that line), however, I installed Deadline 5.2 from scratch, freshly, even into a different destination folder, than the “old” Deadline. So, this is a weird one.

I’ll try to reinstall it and let you knoe.

Thank you again, cheers…

loocas · July 30, 2012, 9:34am

Ok, so I just reinstalled Deadline 5.2 (repository) on the server and I’m still getting the same errors!

error_report.txt (376 KB)

rrussell · July 30, 2012, 1:10pm

Thanks for posting the log. There is definitely a version mismatch:

This shows that 3dsmax is still loading an old version of Lightning, so that would explain why it’s complaining about the commands it’s getting. I also found this in the log:

It appears that you have an older version of Lightning as part of your plugin sync script. Because Max loads this old version, it’s unable to load the new version that we point it to at startup. You should try removing this old version of lightning from your plugin sync, and make sure it is removed from the plugin folders on your render machines. This should solve the problem.

Cheers,

Ryan

loocas · July 30, 2012, 2:19pm

Holy…

where did that come from? I never moved your lightning plugins from the repository to my plugins directory.

Ok, thanks for dissecting that, I’ll try to remove “my” lightning plugins and see if Max picks up yours instead.

Will let you know asap.

loocas · July 30, 2012, 2:25pm

It seems to have been it!

I’m really sorry for this trouble I honestly didn’t have any idea where that Lightning plugin came from in my special plugins directory (does that make me a bad TD? ).

Anyways, that’d explain why I didn’t notice this issue when testing Deadline out in my virtual environment.

So, once again, thank you very much for your help, I appretiate it!

rrussell · July 30, 2012, 3:57pm

No problem! Glad to hear it’s working now!

Rendering in 3ds Max errors out: Unexpected command ...

[code]======================================================= Error Type

======================================================= Error Stack Trace

======================================================= Error Type

======================================================= Error Stack Trace

[code]=======================================================
Error Type

=======================================================
Error Stack Trace

=======================================================
Error Type

=======================================================
Error Stack Trace