Cinema 4D job finished too early

tomasz · September 26, 2016, 8:12am

Hi,

I have a problem with Deadline 8 (v 8.0.8.1) and Cinema 4D R16 jobs. I’ve noticed that from time to time the cinema job is mark as finished even though it is still rendering on the slave. The cinema process on the slave is consuming 100% CPU power and at the same time in deadline monitor the slave is marked as idle. To reproduce this bug I had to start at least 5 Cinema jobs with the same scene before it showed up.

Below I attached the content of the slave log file. I’ll add that this problem showed up after upgrading to Deadline 8. We’ve jumped directly to version 8.0.8.1.

Will try to run more tests to catch the root of the problem. Meanwhile, do you have any idea what can be the cause of this issue?

2016-09-26 09:10:51:  Scheduler Thread - Render Thread 0 completed its task
2016-09-26 09:10:51:  Scheduler Thread - Seconds before next job scan: 2
2016-09-26 09:10:53:  Scheduler - Performing Job scan on Primary Pools with scheduling order Pool, Priority, First-in First-out
2016-09-26 09:10:53:  Scheduler - Job chooser found no jobs.
2016-09-26 09:10:53:  Scheduler - Performing Job scan on Secondary Pools with scheduling order Pool, Priority, First-in First-out
2016-09-26 09:10:53:  Scheduler - Successfully dequeued 1 task(s).  Returning.

# CINEMA JOB

2016-09-26 09:10:53:  INFO: Starting the job: 57e8c9eaac3c1c08c02ef2b4
2016-09-26 09:10:53:  0: Shutdown
2016-09-26 09:10:54:  0: Exited ThreadMain(), cleaning up...
2016-09-26 09:10:54:  0: Executing plugin command of type 'End Job'
2016-09-26 09:10:54:  0: INFO: End Job called - shutting down 3dsmax plugin
2016-09-26 09:10:55:  0: INFO: Disconnecting socket connection to 3dsmax
2016-09-26 09:10:55:  0: INFO: Waiting for 3dsmax to shut down
2016-09-26 09:10:55:  0: INFO: 3dsmax has shut down
2016-09-26 09:10:55:  0: Done executing plugin command of type 'End Job'
2016-09-26 09:10:55:  0: Stopped job: [**CUT**] [Frame 0 - 25 Tiles]
2016-09-26 09:10:55:  0: Unloading plugin: 3dsmax
2016-09-26 09:10:56:  Scheduler - Returning limit stubs not in use.
2016-09-26 09:10:56:  Scheduler -   returning 57e8c1039a2c025284a0927b
2016-09-26 09:10:56:  Scheduler Thread - All job files are already synchronized
2016-09-26 09:10:56:  Syncronizing Plugin Cinema4D from \\[**CUT**]\deadline\DeadlineRepository8\plugins\Cinema4D
2016-09-26 09:10:57:  Syncronization complete. Took: 0 seconds
2016-09-26 09:10:57:  0: Got task!
2016-09-26 09:10:59:  0: Loaded plugin Cinema4D (\\[**CUT**]\deadline\DeadlineRepository8\plugins\Cinema4D)
2016-09-26 09:10:59:  0: Executing plugin command of type 'Initialize Plugin'
2016-09-26 09:10:59:  0: INFO: Executing plugin script 'C:\Users\[**CUT**]\AppData\Local\Thinkbox\Deadline8\slave\xpr17997_107\plugins\57e8c9eaac3c1c08c02ef2b4\Cinema4D.py'
2016-09-26 09:10:59:  0: INFO: About: Cinema 4D Plugin for Deadline
2016-09-26 09:10:59:  0: INFO: The job's environment will be merged with the current environment before rendering
2016-09-26 09:10:59:  0: Done executing plugin command of type 'Initialize Plugin'
2016-09-26 09:11:00:  0: Start Job timeout is disabled.
2016-09-26 09:11:00:  0: Task timeout is disabled.
2016-09-26 09:11:00:  0: Loaded job: [**CUT**][cinema_missing_output][camera_9.c4d] (57e8c9eaac3c1c08c02ef2b4)
2016-09-26 09:11:00:  0: Executing plugin command of type 'Start Job'
2016-09-26 09:11:00:  0: Done executing plugin command of type 'Start Job'
2016-09-26 09:11:00:  0: Plugin rendering frame(s): 1
2016-09-26 09:11:00:  0: Executing plugin command of type 'Render Task'
2016-09-26 09:11:00:  0: INFO: Starting Cinema 4D Task
2016-09-26 09:11:00:  0: INFO: Stdout Redirection Enabled: True
2016-09-26 09:11:00:  0: INFO: Stdout Handling Enabled: True
2016-09-26 09:11:00:  0: INFO: Popup Handling Enabled: True
2016-09-26 09:11:00:  0: INFO: QT Popup Handling Enabled: False
2016-09-26 09:11:00:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2016-09-26 09:11:00:  0: INFO: Using Process Tree: True
2016-09-26 09:11:00:  0: INFO: Hiding DOS Window: True
2016-09-26 09:11:00:  0: INFO: Creating New Console: False
2016-09-26 09:11:00:  0: INFO: Running as user: [**CUT**]
2016-09-26 09:11:00:  0: INFO: Not enforcing a build of Cinema 4D
2016-09-26 09:11:00:  0: INFO: Executable: "C:\Program Files\MAXON\CINEMA 4D R16\Commandline.exe"
2016-09-26 09:11:00:  0: INFO: Rendering main output to network drive
2016-09-26 09:11:00:  0: INFO: Rendering multipass output to network drive
2016-09-26 09:11:00:  0: INFO: Argument:  -nogui -render "\\[**CUT**]\dev\input\6b830cf43f29faaa8ff1972446c5ed90b9c389190bedc266d95c6e459ce87cf2\cinema_missing_output\camera_9.c4d" -frame 1 1 -oimage "\\[**CUT**]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output" -omultipass "\\[**CUT**]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output_MP"
2016-09-26 09:11:00:  0: INFO: Full Command: "C:\Program Files\MAXON\CINEMA 4D R16\Commandline.exe"  -nogui -render "\\[**CUT**]\dev\input\6b830cf43f29faaa8ff1972446c5ed90b9c389190bedc266d95c6e459ce87cf2\cinema_missing_output\camera_9.c4d" -frame 1 1 -oimage "\\[**CUT**]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output" -omultipass "\\[**CUT**]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output_MP"
2016-09-26 09:11:00:  0: INFO: Startup Directory: "C:\Program Files\MAXON\CINEMA 4D R16"
2016-09-26 09:11:00:  0: INFO: Process Priority: BelowNormal
2016-09-26 09:11:00:  0: INFO: Process Affinity: default
2016-09-26 09:11:00:  0: INFO: Process is now running
2016-09-26 09:11:00:  0: STDOUT: Application start      : 09/26/16 at 09:11:00
2016-09-26 09:11:00:  0: STDOUT: Executable             : 64 Bit
2016-09-26 09:11:00:  0: STDOUT: Version / Build        : 16.011 / [**CUT**]07079
2016-09-26 09:11:00:  0: STDOUT: Debugger               : not available
2016-09-26 09:11:00:  0: STDOUT: Memory model           : release
2016-09-26 09:11:00:  0: STDOUT: Startup path           : file:///C:/Program Files/MAXON/CINEMA 4D R16
2016-09-26 09:11:00:  0: STDOUT: Application path       : file:///C:/Program Files/MAXON/CINEMA 4D R16/Commandline.exe
2016-09-26 09:11:00:  0: STDOUT: Application executable : file:///C:/Program Files/MAXON/CINEMA 4D R16/Commandline.exe
2016-09-26 09:11:00:  0: STDOUT: Resource path          : file:///C:/Program Files/MAXON/CINEMA 4D R16/resource
2016-09-26 09:11:00:  0: STDOUT: Module path(s)         : file:///C:/Program Files/MAXON/CINEMA 4D R16/modules
2016-09-26 09:11:00:  0: STDOUT: Temporary path         : file:///C:/Users/[**CUT**]/AppData/Local/Temp
2016-09-26 09:11:00:  0: STDOUT: Prefs path             : file:///C:/Users/[**CUT**]/AppData/Roaming/MAXON/CINEMA 4D R16_14AF56B1
2016-09-26 09:11:00:  0: STDOUT: Global Prefs path      : file:///C:/Users/[**CUT**]/AppData/Roaming/MAXON
2016-09-26 09:11:00:  0: STDOUT: OS version             : Windows 8, 64 Bit, Core (build 9200)
2016-09-26 09:11:00:  0: STDOUT: Processor              : 12x Intel Core i7-5820K CPU 
2016-09-26 09:11:00:  0: STDOUT: Computer name          : [**CUT**]
2016-09-26 09:11:00:  0: STDOUT: Thread Count           : 12
2016-09-26 09:11:00:  0: STDOUT: Cpu Speed (MHz)        : 3300.000
2016-09-26 09:11:00:  0: STDOUT: Application Type       : Modular
2016-09-26 09:11:00:  0: STDOUT: Command line arguments : "-nogui" "-render" "\\[**CUT**]\dev\input\6b830cf43f29faaa8ff1972446c5ed90b9c389190bedc266d95c6e459ce87cf2\cinema_missing_output\camera_9.c4d" "-frame" "1" "1" "-oimage" "\\[**CUT**]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output" "-omultipass" "\\[**CUT**]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output_MP" 
2016-09-26 09:11:00:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/c4dplugin.xdl64 with module(s) net.maxon.c4d.c4dplugin
2016-09-26 09:11:00:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/crashhandler.module.xdl64 with module(s) net.maxon.crashhandler
2016-09-26 09:11:01:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/crypt.module.xdl64 with module(s) net.maxon.crypt
2016-09-26 09:11:01:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/image.module.xdl64 with module(s) net.maxon.image
2016-09-26 09:11:01:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/mesh.module.xdl64 with module(s) net.maxon.mesh
2016-09-26 09:11:01:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/misc.module.xdl64 with module(s) net.maxon.misc
2016-09-26 09:11:01:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/network.module.xdl64 with module(s) net.maxon.network
2016-09-26 09:11:01:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/triangulation.module.xdl64 with module(s) net.maxon.triangulation
2016-09-26 09:11:01:  0: INFO: Process exit code: 0
2016-09-26 09:11:01:  0: INFO: Finished Cinema 4D Task
2016-09-26 09:11:01:  0: Done executing plugin command of type 'Render Task'
2016-09-26 09:11:01:  0: Render time for frame(s): 1.911 s
2016-09-26 09:11:01:  0: Total time for task: 8.742 s
2016-09-26 09:11:01:  0: Saving task log...
2016-09-26 09:11:02:  INFO: Finishing the job: 57e8c9eaac3c1c08c02ef2b4
2016-09-26 09:11:03:  Scheduler Thread - Render Thread 0 completed its task
2016-09-26 09:11:03:  Scheduler Thread - Seconds before next job scan: 2
2016-09-26 09:11:05:  Scheduler - Performing Job scan on Primary Pools with scheduling order Pool, Priority, First-in First-out
2016-09-26 09:11:05:  Scheduler - Job chooser found no jobs.
2016-09-26 09:11:05:  Scheduler - Performing Job scan on Secondary Pools with scheduling order Pool, Priority, First-in First-out
2016-09-26 09:11:05:  Scheduler - Successfully dequeued 1 task(s).  Returning.
2016-09-26 09:11:05:  0: Shutdown
2016-09-26 09:11:06:  0: Exited ThreadMain(), cleaning up...
2016-09-26 09:11:06:  0: Executing plugin command of type 'End Job'
2016-09-26 09:11:06:  0: Done executing plugin command of type 'End Job'
2016-09-26 09:11:06:  0: Stopped job: [**CUT**][cinema_missing_output][camera_9.c4d]
2016-09-26 09:11:06:  0: Unloading plugin: Cinema4D
2016-09-26 09:11:07:  Scheduler - Returning limit stubs not in use.
2016-09-26 09:11:07:  Scheduler -   returning 57e8c9eaac3c1c08c02ef2b4

# NEXT JOB IS BEING TAKEN
# PROCESS Commandline.exe IS STILL RUNNING AND RENDERING THE SCENE

2016-09-26 09:11:07:  Scheduler Thread - Synchronization time for job files: 244.682 ms
2016-09-26 09:11:07:  Syncronizing Plugin 3dsmax from \\[**CUT**]\deadline\DeadlineRepository8\plugins\3dsmax
2016-09-26 09:11:08:  Syncronization complete. Took: 0 seconds
2016-09-26 09:11:09:  0: Got task!

EDIT

I’ve run some additional tests. It looks like this error occurs only when scene is using Corona (alpha v5 fix 1) renderer. When job is running normally, then on the salve no additional windows are visible - only the standard slave window. But, when the error occurs, then there is a windows command line window with Cinema log - see image below.

Windows cmd window with Cinema log.

Windows task manager with visible Cinema process and Cinema cmd window, additionally there is a second 3ds max process which was scheduled after Cinema job was marked as finished.

eamsler · September 26, 2016, 4:24pm

Well, Deadline is checking to see how the app (in this case C4D) was spawned and is running. Considering we got an exit code from Cinema, for all intents and purposes, we think it’s closed and rendered successfully.

I guess as a test, can you try and run the full command to see if it exits while Corona is still rendering away. Looks like based on your edited command, it should be something like this:

“C:\Program Files\MAXON\CINEMA 4D R16\Commandline.exe” -nogui -render “\[CUT]\dev\input\6b830cf43f29faaa8ff1972446c5ed90b9c389190bedc266d95c6e459ce87cf2\cinema_missing_output\camera_9.c4d” -frame 1 1 -oimage “\[CUT]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output” -omultipass “\[CUT]\dev\output\7282f6f96b03ab562c73d3fa3cf56e10\frames\cinema_missing_output_MP”

You’ll likely have to adjust paths for that test, so if you need help there, just let me know.

MikeOwen · September 26, 2016, 5:30pm

Looking at your Task Manager image, Corona is causing the Maxon CommandLine process to be forked or spawned as a separate process whereas it should be a child process of the Deadline Slave application. As Corona is very much in any early ALPHA state as a new renderer for C4D; I would say this is very much a bug on the Corona side of things and should be reported to their development team.

tomasz · September 27, 2016, 7:35am

Thanks for looking into this. I’ve done more research. It looks like the problem is happening when the corona is trying to refresh the license. After forcing all the slaves to reevaluate the license the problem almost disappeared. I can’t reliably reproduce it now, but I’m pretty sure that it will show up soon.

For future reference, I’m putting the c4d log file with correct and incorrect loading behavior.

Incorrect processing, which leaves detached c4d process

2016-09-26 10:52:51:  0: STDOUT: Application start      : 09/26/16 at 10:52:51
2016-09-26 10:52:51:  0: STDOUT: Executable             : 64 Bit
2016-09-26 10:52:51:  0: STDOUT: Version / Build        : 16.011 / RB107079
2016-09-26 10:52:51:  0: STDOUT: Debugger               : not available
2016-09-26 10:52:51:  0: STDOUT: Memory model           : release
2016-09-26 10:52:51:  0: STDOUT: Startup path           : file:///C:/Program Files/MAXON/CINEMA 4D R16
2016-09-26 10:52:51:  0: STDOUT: Application path       : file:///C:/Program Files/MAXON/CINEMA 4D R16/Commandline.exe
2016-09-26 10:52:51:  0: STDOUT: Application executable : file:///C:/Program Files/MAXON/CINEMA 4D R16/Commandline.exe
2016-09-26 10:52:51:  0: STDOUT: Resource path          : file:///C:/Program Files/MAXON/CINEMA 4D R16/resource
2016-09-26 10:52:51:  0: STDOUT: Module path(s)         : file:///C:/Program Files/MAXON/CINEMA 4D R16/modules
2016-09-26 10:52:51:  0: STDOUT: Temporary path         : file:///C:/Users/RENDER~1/AppData/Local/Temp
2016-09-26 10:52:51:  0: STDOUT: Prefs path             : file:///C:/Users/renderbuzz/AppData/Roaming/MAXON/CINEMA 4D R16_14AF56B1
2016-09-26 10:52:51:  0: STDOUT: Global Prefs path      : file:///C:/Users/renderbuzz/AppData/Roaming/MAXON
2016-09-26 10:52:51:  0: STDOUT: OS version             : Windows 8, 64 Bit, Core (build 9200)
2016-09-26 10:52:51:  0: STDOUT: Processor              : 12x Intel Core i7-5820K CPU 
2016-09-26 10:52:51:  0: STDOUT: Computer name          : [ **CUT**]
2016-09-26 10:52:51:  0: STDOUT: Thread Count           : 12
2016-09-26 10:52:51:  0: STDOUT: Cpu Speed (MHz)        : 3300.000
2016-09-26 10:52:51:  0: STDOUT: Application Type       : Modular
2016-09-26 10:52:51:  0: STDOUT: Command line arguments : "-nogui" "-render" "\\[ **CUT **]\input\db28add3d5da83a0d2fa7aaa632f29de77092ff3df024588ca8a9f7ad7b0a\009\009.c4d" "-frame" "1" "1" "-oimage" "\\[ **CUT **]\output\3f33216eab1ee0122aed93aec000e\frames\009" "-omultipass" "\\[ **CUT **]\output\3f33216eab1ee0122aed93aec000e\frames\009_MP" 
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/c4dplugin.xdl64 with module(s) net.maxon.c4d.c4dplugin
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/crashhandler.module.xdl64 with module(s) net.maxon.crashhandler
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/crypt.module.xdl64 with module(s) net.maxon.crypt
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/image.module.xdl64 with module(s) net.maxon.image
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/mesh.module.xdl64 with module(s) net.maxon.mesh
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/misc.module.xdl64 with module(s) net.maxon.misc
2016-09-26 10:52:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/network.module.xdl64 with module(s) net.maxon.network
2016-09-26 10:52:52:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/triangulation.module.xdl64 with module(s) net.maxon.triangulation
2016-09-26 10:52:52:  0: INFO: Process exit code: 0
2016-09-26 10:52:52:  0: INFO: Finished Cinema 4D Task
2016-09-26 10:52:52:  0: Done executing plugin command of type 'Render Task'
2016-09-26 10:52:52:  0: Render time for frame(s): 1.909 s
2016-09-26 10:52:52:  0: Total time for task: 8.830 s
2016-09-26 10:52:52:  0: Saving task log...

Correct c4d processing:

2016-09-26 10:53:50:  0: STDOUT: Application start      : 09/26/16 at 10:53:50
2016-09-26 10:53:50:  0: STDOUT: Executable             : 64 Bit
2016-09-26 10:53:50:  0: STDOUT: Version / Build        : 16.011 / RB107079
2016-09-26 10:53:50:  0: STDOUT: Debugger               : not available
2016-09-26 10:53:50:  0: STDOUT: Memory model           : release
2016-09-26 10:53:50:  0: STDOUT: Startup path           : file:///C:/Program Files/MAXON/CINEMA 4D R16
2016-09-26 10:53:50:  0: STDOUT: Application path       : file:///C:/Program Files/MAXON/CINEMA 4D R16/Commandline.exe
2016-09-26 10:53:50:  0: STDOUT: Application executable : file:///C:/Program Files/MAXON/CINEMA 4D R16/Commandline.exe
2016-09-26 10:53:50:  0: STDOUT: Resource path          : file:///C:/Program Files/MAXON/CINEMA 4D R16/resource
2016-09-26 10:53:50:  0: STDOUT: Module path(s)         : file:///C:/Program Files/MAXON/CINEMA 4D R16/modules
2016-09-26 10:53:50:  0: STDOUT: Temporary path         : file:///C:/Users/RENDER~1/AppData/Local/Temp
2016-09-26 10:53:50:  0: STDOUT: Prefs path             : file:///C:/Users/renderbuzz/AppData/Roaming/MAXON/CINEMA 4D R16_14AF56B1
2016-09-26 10:53:50:  0: STDOUT: Global Prefs path      : file:///C:/Users/renderbuzz/AppData/Roaming/MAXON
2016-09-26 10:53:50:  0: STDOUT: OS version             : Windows 8, 64 Bit, Core (build 9200)
2016-09-26 10:53:50:  0: STDOUT: Processor              : 12x Intel Core i7-5820K CPU 
2016-09-26 10:53:50:  0: STDOUT: Computer name          : XPR18025_134
2016-09-26 10:53:50:  0: STDOUT: Thread Count           : 12
2016-09-26 10:53:50:  0: STDOUT: Cpu Speed (MHz)        : 3300.000
2016-09-26 10:53:50:  0: STDOUT: Application Type       : Modular
2016-09-26 10:53:50:  0: STDOUT: Command line arguments : "-nogui" "-render" "\\[**CUT**]\input\db28add3d5da83a0d2fa7aaa632f29de77092ff3df024588ca8a9f7ad7b0a\009\009.c4d" "-frame" "1" "1" "-oimage" "\\[**CUT**]\output\694c06bdce65d27888308bf387323\frames\009" "-omultipass" "\\[**CUT**]\output\694c06bdce65d27888308bf387323\frames\009_MP" 
2016-09-26 10:53:50:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/c4dplugin.xdl64 with module(s) net.maxon.c4d.c4dplugin
2016-09-26 10:53:50:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/crashhandler.module.xdl64 with module(s) net.maxon.crashhandler
2016-09-26 10:53:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/crypt.module.xdl64 with module(s) net.maxon.crypt
2016-09-26 10:53:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/image.module.xdl64 with module(s) net.maxon.image
2016-09-26 10:53:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/mesh.module.xdl64 with module(s) net.maxon.mesh
2016-09-26 10:53:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/misc.module.xdl64 with module(s) net.maxon.misc
2016-09-26 10:53:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/network.module.xdl64 with module(s) net.maxon.network
2016-09-26 10:53:51:  0: STDOUT: Loading file:///C:/Program Files/MAXON/CINEMA 4D R16/modules/triangulation.module.xdl64 with module(s) net.maxon.triangulation
2016-09-26 10:54:01:  0: STDOUT: ========================================
2016-09-26 10:54:01:  0: STDOUT:  Corona for Cinema 4D, version A5.1 
2016-09-26 10:54:01:  0: STDOUT:  Version of core 1.4 
2016-09-26 10:54:01:  0: STDOUT:  (c) Render Legion s.r.o., 2014-2016
2016-09-26 10:54:01:  0: STDOUT:  Plugin successfully loaded
2016-09-26 10:54:01:  0: STDOUT: ========================================
2016-09-26 10:54:02:  0: STDOUT: Loading Project: \\[**CUT**]\input\db28add3d5da83a0d2fa7aaa632f29de77092ff3df024588ca8a9f7ad7b0a148\009\009.c4d
2016-09-26 10:54:08:  0: STDOUT: Rendering frame 1 at <Mon Sep 26 10:54:08 2016>
2016-09-26 10:54:08:  0: STDOUT: Rendering Phase: Setup
2016-09-26 10:54:08:  0: STDOUT: Progress: 0%
2016-09-26 10:54:09:  0: STDOUT: [Corona4D] Parsing of material BlackPlastic took 0 seconds

NORMAL PROCESSING

MikeOwen · September 27, 2016, 8:36am

Thanks for the additional info. I will report it to the Corona development team for investigation.