Hey,
Trying to submit renders to Deadline (6.2.0.32), with After Effects CC 2014 and getting the following error;
Error occurred while writing report log: Could not find a part of the path ‘Y:\deadline\reports\jobs\1b\b\5412bc61a5577213e82981bb.bz2’. (System.IO.DirectoryNotFoundException)
After Effects CS6 works fine.
Hi Toke,
Can you check if the following folder exists?
Y:\deadline\reports\jobs\1b\b\
If it does, can you check if the machine that reported this error can see that folder?
If it doesn’t, it sounds like you might have a rogue Deadline 5 slave on your network, which will purge report folders and cause this problem. See this forum thread post for more info on how to check for this: viewtopic.php?f=11&t=10590&p=48366&hilit=rogue#p47814
If the above isn’t the issue, could you post the full submission log/error message stack if available?
Y:\deadline\reports\jobs\1b\b\
This folder doesn’t exist, the reports/jobs folder is empty.
There are no rogue Deadline 5 slaves on the network, as I only have one slave on (my workstation) and the Deadline server running.
If the above isn’t the issue, could you post the full submission log/error message stack if available?
What logs are referring to? Slave reports?
this is the slave log;
2014-09-12 11:32:56: BEGIN - SHREDDER\admin
2014-09-12 11:32:56: Deadline Slave 6.2 [v6.2.0.32 R (2563d5bc8)]
2014-09-12 11:33:00: Auto Configuration: No auto configuration could be detected, using local configuration
2014-09-12 11:33:02: Info Thread - Created.
2014-09-12 11:33:04: Trying to connect using license server ‘@BUMPYBOXSERVER’…
2014-09-12 11:33:04: License obtained.
2014-09-12 11:33:25: Update timeout has been set to 1800 seconds
2014-09-12 11:33:25: Stdout Handling Enabled: False
2014-09-12 11:33:25: Popup Handling Enabled: False
2014-09-12 11:33:25: Using Process Tree: True
2014-09-12 11:33:25: Hiding DOS Window: True
2014-09-12 11:33:25: Creating New Console: False
2014-09-12 11:33:25: Executable: “C:\Program Files\Thinkbox\Deadline6\bin\deadlinecommand.exe”
2014-09-12 11:33:25: Argument: -DoPendingJobScan False
2014-09-12 11:33:25: Startup Directory: “C:\Program Files\Thinkbox\Deadline6\bin”
2014-09-12 11:33:25: Process Priority: BelowNormal
2014-09-12 11:33:25: Process Affinity: default
2014-09-12 11:33:25: Process is now running
2014-09-12 11:33:26: Performing Pending Job Scan…
2014-09-12 11:33:26: Pending Job Scan - Loading pending and active jobs
2014-09-12 11:33:26: Pending Job Scan - Loaded 1 pending and active jobs in 134.008 ms
2014-09-12 11:33:26: Pending Job Scan - Scanning pending and active jobs
2014-09-12 11:33:26: Pending Job Scan - Released 0 pending jobs and 0 pending tasks in 5.000 ms
2014-09-12 11:33:26: Pending Job Scan - Done.
2014-09-12 11:33:27: Process exit code: 0
2014-09-12 11:33:27: Update timeout has been set to 1800 seconds
2014-09-12 11:33:27: Stdout Handling Enabled: False
2014-09-12 11:33:27: Popup Handling Enabled: False
2014-09-12 11:33:27: Using Process Tree: True
2014-09-12 11:33:27: Hiding DOS Window: True
2014-09-12 11:33:27: Creating New Console: False
2014-09-12 11:33:27: Executable: “C:\Program Files\Thinkbox\Deadline6\bin\deadlinecommand.exe”
2014-09-12 11:33:27: Argument: -DoHouseCleaning 100 False
2014-09-12 11:33:27: Startup Directory: “C:\Program Files\Thinkbox\Deadline6\bin”
2014-09-12 11:33:27: Process Priority: BelowNormal
2014-09-12 11:33:27: Process Affinity: default
2014-09-12 11:33:27: Process is now running
2014-09-12 11:33:28: Performing Stalled Slave Scan…
2014-09-12 11:33:28: Stalled Slave Scan - Loading slave states
2014-09-12 11:33:28: Stalled Slave Scan - Loaded 6 slave states in 152.009 ms
2014-09-12 11:33:28: Stalled Slave Scan - Scanning slave states
2014-09-12 11:33:28: Stalled Slave Scan - Cleaned up 0 stalled slaves in 184.010 ms
2014-09-12 11:33:28: Stalled Slave Scan - Done.
2014-09-12 11:33:29: Process exit code: 0
2014-09-12 11:33:29: Scheduler Thread - Synchronizing job auxiliary files from Y:\deadline\jobs\5412bc47a557720b58f7e706
2014-09-12 11:33:29: Scheduler Thread - All job files are already synchronized
2014-09-12 11:33:29: Scheduler Thread - Synchronizing plugin files from Y:\deadline\plugins\AfterEffects
2014-09-12 11:33:30: Scheduler Thread - Synchronization time for plugin files: 71.004 ms
2014-09-12 11:33:30: Constructor: AfterEffects
2014-09-12 11:33:31: 0: Task timeout is disabled.
2014-09-12 11:33:31: 0: Loaded job: temp.aep - Comp 1 (5412bc47a557720b58f7e706)
2014-09-12 11:33:31: 0: INFO: Executing plugin script C:\Users\admin\AppData\Local\Thinkbox\Deadline6\slave\SHREDDER\plugins\5412bc47a557720b58f7e706\AfterEffects.py
2014-09-12 11:33:31: 0: INFO: Fail on Existing After Effects process: 0
2014-09-12 11:33:31: 0: INFO: About: After Effects Plugin for Deadline
2014-09-12 11:33:31: 0: INFO: The current environment will be used for rendering
2014-09-12 11:33:31: 0: Plugin rendering frame(s): 0
2014-09-12 11:33:31: 0: INFO: Stdout Handling Enabled: True
2014-09-12 11:33:31: 0: INFO: Popup Handling Enabled: True
2014-09-12 11:33:31: 0: INFO: Using Process Tree: True
2014-09-12 11:33:31: 0: INFO: Hiding DOS Window: True
2014-09-12 11:33:31: 0: INFO: Creating New Console: False
2014-09-12 11:33:33: Scheduler Thread - Render Thread 0 threw a major error:
2014-09-12 11:33:33: >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
2014-09-12 11:33:33: Exception Details
2014-09-12 11:33:33: RenderPluginException – Error in RenderExecutable: GetConfigEntry: Attempted to access non-existent config key RenderExecutable13_0 (Deadline.Plugins.RenderPluginException)
2014-09-12 11:33:33: at Deadline.Plugins.ScriptPlugin.GetConfigEntry(String key)
2014-09-12 11:33:33: at Deadline.Plugins.DeadlinePlugin.GetConfigEntry(String key)
2014-09-12 11:33:33: at Python.Runtime.Dispatcher.TrueDispatch(ArrayList args)
2014-09-12 11:33:33: at Python.Runtime.Dispatcher.Dispatch(ArrayList args)
2014-09-12 11:33:33: at __FranticX_GenericDelegate0`1[[System_String, mscorlib, Version=4_0_0_0, Culture=neutral, PublicKeyToken=b77a5c561934e089]]Dispatcher.Invoke()
2014-09-12 11:33:33: at FranticX.Processes.ManagedProcess.RenderExecutable()
2014-09-12 11:33:33: at FranticX.Processes.ManagedProcess.Execute(Boolean waitForExit)
2014-09-12 11:33:33: at Deadline.Plugins.ScriptPlugin.RenderTasks(String taskId, Int32 startFrame, Int32 endFrame, String& outMessage, AbortLevel& abortLevel)
2014-09-12 11:33:33: RenderPluginException.Cause: JobError (2)
2014-09-12 11:33:33: RenderPluginException.Level: Major (1)
2014-09-12 11:33:33: RenderPluginException.HasSlaveLog: True
2014-09-12 11:33:33: Exception.Data: ( )
2014-09-12 11:33:33: Exception.TargetSite: Void RenderTask(System.String, Int32, Int32)
2014-09-12 11:33:33: Exception.Source: deadline
2014-09-12 11:33:33: Exception.HResult: -2146233088
2014-09-12 11:33:33: Exception.StackTrace:
2014-09-12 11:33:33: at Deadline.Plugins.Plugin.RenderTask(String taskId, Int32 startFrame, Int32 endFrame)
2014-09-12 11:33:33: at Deadline.Slaves.SlaveRenderThread.RenderCurrentTask(TaskLogWriter tlw)
2014-09-12 11:33:33: <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
2014-09-12 11:33:33: Error occurred while writing report log:
2014-09-12 11:33:33: Exception Details
2014-09-12 11:33:33: DirectoryNotFoundException – Could not find a part of the path ‘Y:\deadline\reports\jobs\11\6\5412cbfca557720a1c5e9116.bz2’.
2014-09-12 11:33:33: Exception.Data: ( )
2014-09-12 11:33:33: Exception.TargetSite: Void WinIOError(Int32, System.String)
2014-09-12 11:33:33: Exception.Source: mscorlib
2014-09-12 11:33:33: Exception.HResult: -2147024893
2014-09-12 11:33:33: Exception.StackTrace:
2014-09-12 11:33:33: at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath)
2014-09-12 11:33:33: at System.IO.File.InternalCopy(String sourceFileName, String destFileName, Boolean overwrite, Boolean checkHost)
2014-09-12 11:33:33: at Deadline.StorageDB.JobStorage.WriteJobReportFile(Report report, String reportLog)
2014-09-12 11:33:35: Update timeout has been set to 1800 seconds
2014-09-12 11:33:35: Stdout Handling Enabled: False
2014-09-12 11:33:35: Popup Handling Enabled: False
2014-09-12 11:33:35: Using Process Tree: True
2014-09-12 11:33:35: Hiding DOS Window: True
2014-09-12 11:33:35: Creating New Console: False
2014-09-12 11:33:35: Executable: “C:\Program Files\Thinkbox\Deadline6\bin\deadlinecommand.exe”
2014-09-12 11:33:35: Argument: -DoPendingJobScan False
2014-09-12 11:33:35: Startup Directory: “C:\Program Files\Thinkbox\Deadline6\bin”
2014-09-12 11:33:35: Process Priority: BelowNormal
2014-09-12 11:33:35: Process Affinity: default
2014-09-12 11:33:35: Process is now running
2014-09-12 11:33:36: Performing Pending Job Scan…
2014-09-12 11:33:36: Pending Job Scan - Loading pending and active jobs
2014-09-12 11:33:36: Pending Job Scan - Loaded 1 pending and active jobs in 121.007 ms
2014-09-12 11:33:36: Pending Job Scan - Scanning pending and active jobs
2014-09-12 11:33:36: Pending Job Scan - Released 0 pending jobs and 0 pending tasks in 5.000 ms
2014-09-12 11:33:36: Pending Job Scan - Done.
2014-09-12 11:33:37: Process exit code: 0
2014-09-12 11:33:37: Update timeout has been set to 1800 seconds
2014-09-12 11:33:37: Stdout Handling Enabled: False
2014-09-12 11:33:37: Popup Handling Enabled: False
2014-09-12 11:33:37: Using Process Tree: True
2014-09-12 11:33:37: Hiding DOS Window: True
2014-09-12 11:33:37: Creating New Console: False
2014-09-12 11:33:37: Executable: “C:\Program Files\Thinkbox\Deadline6\bin\deadlinecommand.exe”
2014-09-12 11:33:37: Argument: -DoHouseCleaning 100 False
2014-09-12 11:33:37: Startup Directory: “C:\Program Files\Thinkbox\Deadline6\bin”
2014-09-12 11:33:37: Process Priority: BelowNormal
2014-09-12 11:33:37: Process Affinity: default
2014-09-12 11:33:37: Process is now running
2014-09-12 11:33:38: Performing Stalled Slave Scan…
2014-09-12 11:33:38: Stalled Slave Scan - Loading slave states
2014-09-12 11:33:38: Stalled Slave Scan - Loaded 6 slave states in 149.008 ms
2014-09-12 11:33:38: Stalled Slave Scan - Scanning slave states
2014-09-12 11:33:38: Stalled Slave Scan - Cleaned up 0 stalled slaves in 188.011 ms
2014-09-12 11:33:38: Stalled Slave Scan - Done.
2014-09-12 11:33:39: Process exit code: 0
2014-09-12 11:33:39: Scheduler Thread - Synchronizing job auxiliary files from Y:\deadline\jobs\5412bc47a557720b58f7e706
2014-09-12 11:33:39: Scheduler Thread - All job files are already synchronized
2014-09-12 11:33:40: Constructor: AfterEffects
2014-09-12 11:33:40: 0: Task timeout is disabled.
2014-09-12 11:33:40: 0: Loaded job: temp.aep - Comp 1 (5412bc47a557720b58f7e706)
2014-09-12 11:33:40: 0: INFO: Executing plugin script C:\Users\admin\AppData\Local\Thinkbox\Deadline6\slave\SHREDDER\plugins\5412bc47a557720b58f7e706\AfterEffects.py
2014-09-12 11:33:40: 0: INFO: Fail on Existing After Effects process: 0
2014-09-12 11:33:40: 0: INFO: About: After Effects Plugin for Deadline
2014-09-12 11:33:40: 0: INFO: The current environment will be used for rendering
2014-09-12 11:33:40: 0: Plugin rendering frame(s): 0
2014-09-12 11:33:40: 0: INFO: Stdout Handling Enabled: True
2014-09-12 11:33:40: 0: INFO: Popup Handling Enabled: True
2014-09-12 11:33:40: 0: INFO: Using Process Tree: True
2014-09-12 11:33:40: 0: INFO: Hiding DOS Window: True
2014-09-12 11:33:40: 0: INFO: Creating New Console: False
2014-09-12 11:33:42: Info Thread - requesting slave info thread quit.
2014-09-12 11:33:43: Scheduler Thread - shutdown complete
2014-09-12 11:33:46: Info Thread - shutdown complete
Thanks Toke.
So, there is another exception error before the job report error:
Exception Details
2014-09-12 11:33:33: RenderPluginException – Error in RenderExecutable: GetConfigEntry: Attempted to access non-existent config key RenderExecutable13_0 (Deadline.Plugins.RenderPluginException)
This is due to a lack of AE CC 2014 support in Deadline v6.2.0.32 (Deadline v6.2 was released before CC 2014 came out). Do you have access to the Deadline v6 beta forum/builds? If so, there is currently a v6.2.1 maintenance pack which adds AE CC 2014 support, which is currently in beta and will be released in a few weeks. That 2nd error still shouldn’t happen though, so I will escalate that issue.
Thanks Mike! Updating to 6.2.1 worked.