=======================================================
Log
=======================================================
2019-11-12 15:11:21:  0: Loading Job's Plugin timeout is Disabled
2019-11-12 15:11:24:  0: Executing plugin command of type 'Sync Files for Job'
2019-11-12 15:11:24:  0: Synchronization time for job files: 124.988 ms
2019-11-12 15:11:24:  0: Synchronizing Plugin Houdini from \\svg-deadline02\deadlinerepository10$\plugins\Houdini took: 0 seconds
2019-11-12 15:11:24:  0: Done executing plugin command of type 'Sync Files for Job'
2019-11-12 15:11:24:  0: Executing plugin command of type 'Initialize Plugin'
2019-11-12 15:11:25:  0: INFO: Executing plugin script 'C:\Users\render\AppData\Local\Thinkbox\Deadline10\slave\RENDER39\plugins\5dcabd433698c24740afe3af\Houdini.py'
2019-11-12 15:11:25:  0: INFO: About: Houdini Plugin for Deadline
2019-11-12 15:11:25:  0: INFO: Render Job As User disabled, running as current user 'render'
2019-11-12 15:11:25:  0: INFO: The job's environment will be merged with the current environment before rendering
2019-11-12 15:11:25:  0: Done executing plugin command of type 'Initialize Plugin'
2019-11-12 15:11:25:  0: Start Job timeout is disabled.
2019-11-12 15:11:25:  0: Task timeout is disabled.
2019-11-12 15:11:25:  0: Loaded job: renderFarm_Test_simple_01 - /out/vray_simpleTest (5dcabd433698c24740afe3af)
2019-11-12 15:11:26:  0: Executing plugin command of type 'Start Job'
2019-11-12 15:11:26:  0: DEBUG: S3BackedCache Client is not installed.
2019-11-12 15:11:26:  0: INFO: Executing global asset transfer preload script 'C:\Users\render\AppData\Local\Thinkbox\Deadline10\slave\RENDER39\plugins\5dcabd433698c24740afe3af\GlobalAssetTransferPreLoad.py'
2019-11-12 15:11:26:  0: INFO: Looking for AWS Portal File Transfer...
2019-11-12 15:11:26:  0: INFO: Looking for File Transfer controller in C:/Program Files/Thinkbox/S3BackedCache/bin/task.py...
2019-11-12 15:11:26:  0: INFO: Could not find AWS Portal File Transfer.
2019-11-12 15:11:26:  0: INFO: AWS Portal File Transfer is not installed on the system.
2019-11-12 15:11:26:  0: INFO: Executing job preload script 'C:\Users\render\AppData\Local\Thinkbox\Deadline10\slave\RENDER39\plugins\5dcabd433698c24740afe3af\JobPreLoad.py'
2019-11-12 15:11:26:  0: INFO: JobName: renderFarm_Test_simple_01 - /out/vray_simpleTest
2019-11-12 15:11:26:  0: INFO: JobId: 5dcabd433698c24740afe3af
2019-11-12 15:11:26:  0: INFO: Setting HOUDINI_NO_ENV_FILE to: 1
2019-11-12 15:11:26:  0: INFO: Setting VRAY_APPSDK to: //SVG-DS01/software/vrayHoudini/appsdk
2019-11-12 15:11:26:  0: INFO: Setting VRAY_OSL_PATH to: //SVG-DS01/software/vrayHoudini/appsdk/bin
2019-11-12 15:11:26:  0: INFO: Setting VRAY_UI_DS_PATH to: //SVG-DS01/software/vrayHoudini/ui
2019-11-12 15:11:26:  0: INFO: Setting VFH_HOME to: //SVG-DS01/software/vrayHoudini/vfh_home
2019-11-12 15:11:26:  0: INFO: Setting PATH to: C:/Program Files/Side Effects Software/Houdini 17.5.293/bin;//SVG-DS01/software/vrayHoudini/vfh_home/bin;//SVG-DS01/software/vrayHoudini/appsdk/bin
2019-11-12 15:11:26:  0: INFO: Setting HOUDINI_PATH to: //SVG-DS01/software/vrayHoudini/vfh_home;&
2019-11-12 15:11:26:  0: INFO: Setting HOUDINI13_VOLUME_COMPATIBILITY to: 1
2019-11-12 15:11:26:  0: INFO: Setting HDF5_DISABLE_VERSION_CHECK to: 1
2019-11-12 15:11:26:  0: Done executing plugin command of type 'Start Job'
2019-11-12 15:11:26:  0: Plugin rendering frame(s): 1
2019-11-12 15:11:26:  0: Executing plugin command of type 'Render Task'
2019-11-12 15:11:26:  0: INFO: Starting Houdini Job
2019-11-12 15:11:26:  0: INFO: Stdout Redirection Enabled: True
2019-11-12 15:11:26:  0: INFO: Stdout Handling Enabled: True
2019-11-12 15:11:26:  0: INFO: Popup Handling Enabled: True
2019-11-12 15:11:26:  0: INFO: QT Popup Handling Enabled: False
2019-11-12 15:11:26:  0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2019-11-12 15:11:26:  0: INFO: Using Process Tree: True
2019-11-12 15:11:26:  0: INFO: Hiding DOS Window: True
2019-11-12 15:11:26:  0: INFO: Creating New Console: False
2019-11-12 15:11:26:  0: INFO: Running as user: render
2019-11-12 15:11:26:  0: INFO: Executable: "C:\Program Files\Side Effects Software\Houdini 17.5.293\bin\Hython.exe"
2019-11-12 15:11:26:  0: INFO: Argument: "C:\Users\render\AppData\Local\Thinkbox\Deadline10\slave\RENDER39\plugins\5dcabd433698c24740afe3af\hrender_dl.py" -f 1 1 1 -g -d /out/vray_simpleTest "C:/Users/render/AppData/Local/Thinkbox/Deadline10/slave/RENDER39/jobsData/5dcabd433698c24740afe3af/renderFarm_Test_simple_01.hip"
2019-11-12 15:11:26:  0: INFO: Full Command: "C:\Program Files\Side Effects Software\Houdini 17.5.293\bin\Hython.exe" "C:\Users\render\AppData\Local\Thinkbox\Deadline10\slave\RENDER39\plugins\5dcabd433698c24740afe3af\hrender_dl.py" -f 1 1 1 -g -d /out/vray_simpleTest "C:/Users/render/AppData/Local/Thinkbox/Deadline10/slave/RENDER39/jobsData/5dcabd433698c24740afe3af/renderFarm_Test_simple_01.hip"
2019-11-12 15:11:26:  0: INFO: Startup Directory: "C:\Program Files\Side Effects Software\Houdini 17.5.293\bin"
2019-11-12 15:11:26:  0: INFO: Process Priority: BelowNormal
2019-11-12 15:11:26:  0: INFO: Process Affinity: default
2019-11-12 15:11:26:  0: INFO: Process is now running
2019-11-12 15:11:32:  0: STDOUT: VFH [Info] * Build 3551ba6 from 26 Jul 2019 18:37
2019-11-12 15:11:32:  0: STDOUT: Detected Houdini version: (17, 5, 293)
2019-11-12 15:11:32:  0: STDOUT: ['C:\\Users\\render\\AppData\\Local\\Thinkbox\\Deadline10\\slave\\RENDER39\\plugins\\5dcabd433698c24740afe3af\\hrender_dl.py', '-f', '1', '1', '1', '-g', '-d', '/out/vray_simpleTest', 'C:/Users/render/AppData/Local/Thinkbox/Deadline10/slave/RENDER39/jobsData/5dcabd433698c24740afe3af/renderFarm_Test_simple_01.hip']
2019-11-12 15:11:32:  0: STDOUT: Start: 1
2019-11-12 15:11:32:  0: STDOUT: End: 1
2019-11-12 15:11:32:  0: STDOUT: Increment: 1
2019-11-12 15:11:32:  0: STDOUT: Ignore Inputs: True
2019-11-12 15:11:32:  0: STDOUT: No output specified. Output will be handled by the driver
2019-11-12 15:11:32:  0: STDOUT: Driver: /out/vray_simpleTest
2019-11-12 15:11:32:  0: STDOUT: Input File: C:/Users/render/AppData/Local/Thinkbox/Deadline10/slave/RENDER39/jobsData/5dcabd433698c24740afe3af/renderFarm_Test_simple_01.hip
2019-11-12 15:11:43:  0: STDOUT: Warnings were generated during load.
2019-11-12 15:11:43:  0: STDOUT: Error loading: C:/Users/render/AppData/Local/Thinkbox/Deadline10/slave/RENDER39/jobsData/5dcabd433698c24740afe3af/renderFarm_Test_simple_01.hip
2019-11-12 15:11:43:  0: STDOUT: Warning:     The following node types are using incomplete asset definitions:
2019-11-12 15:11:43:  0: STDOUT:     Driver/deadline
2019-11-12 15:11:43:  0: STDOUT: ROP type: vray_renderer
2019-11-12 15:11:43:  0: STDOUT: Rendering frame 1
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] * V-Ray 4.12.03
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Loading plugins from "\\SVG-DS01\software\vrayHoudini\appsdk\bin/plugins/vray_*.dll"
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: 90 plugin(s) loaded successfully
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Finished loading plugins.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] * Processing scene...
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] * Scene processing time: 0h 0m 0.0s
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] * Starting render for frame 1...
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: GI engines: 'Brute force' and 'Light cache'
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Preparing renderer...
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Using Intel Embree ray server
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Scanning scene for light plugins.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: 2 lights found.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Using adaptive light evaluation with 8 lights.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Light tree is enabled with clamp coefficient 0.1 and even split coefficient 0.5.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Using normal thread priority.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Max ray intensity is enabled: rendered result may have incorrect brightness.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Consistent lighting elements are enabled.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Using Embree ray tracing.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Building Embree static trees took 26 milliseconds, memory used 0.01 MB
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Embree dynamic geometry tree contains 2 primitives.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Embree dynamic geometry tree built in 0.60 ms.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Embree dynamic geometry tree takes 0.01 MB.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Using 0.76 MB for adaptive lights buffer
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Building light cache.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Tracing 1000000 image samples for light cache in 64 passes.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Using 28.23 MB for light cache preview buffer
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Building light cache... 94%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Building light cache... 100%
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Merging light cache passes.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Light cache contains 482 samples.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Light cache base memory usage is 7.3 MB.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Prefiltering light cache.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Average rays per light cache sample: 195.11 (min 1, max 733)
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Start progressive rendering, server mode 0, perform rendering 1
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Adaptive light grid built in 12.83 ms.
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Progressive sampling base buffer memory usage: 29.49 MB
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Additional progressive sampling filter buffers memory usage: 314.25 MB
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1)... 1%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 2)... 2%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 3)... 2%
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal lowered to 0.166667
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal lowered to 0.125000
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 11)... 45%
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal lowered to 0.083333
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal lowered to 0.050000
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 19)... 44%
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal lowered to 0.027778
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal lowered to 0.014706
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 27)... 45%
2019-11-12 15:11:43:  0: STDOUT: VFH [Info] V-Ray: Noise threshold goal set: 0.010000
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 35)... 50%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 39)... 53%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 43)... 55%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 49)... 58%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 56)... 61%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 67)... 62%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 81)... 66%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 95)... 67%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 109)... 69%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 125)... 71%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 143)... 73%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 162)... 75%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 183)... 76%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 202)... 78%
2019-11-12 15:11:43:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 229)... 79%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 258)... 81%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 291)... 82%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 327)... 84%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 375)... 86%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 428)... 88%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 495)... 90%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 563)... 92%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 647)... 94%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 733)... 95%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 798)... 96%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 863)... 97%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 943)... 97%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1031)... 98%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1135)... 98%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1235)... 99%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1363)... 99%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1478)... 99%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1639)... 99%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1791)... 99%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Rendering image (pass 1947)... 99%
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Progressive rendering finished: specified noise level reached
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Sampling level reached: 43.90 subdivs (2028 passes)
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Saving raw image file... 37%
2019-11-12 15:11:50:  0: STDOUT: VFH [Progress] V-Ray: Saving raw image file... 100%
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Successfully written image file "\\svg-fs2019\Projekte_2019\testprojekt_VrayHoudini\render_simple\vray_simpleTest.0001.exr"
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Number of raycasts: 60861818 (66.04 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Camera rays: 9397226 (10.20 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Shadow rays: 22637311 (24.56 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: GI rays: 28859047 (31.31 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Reflection rays: 0 (0.00 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Refraction rays: 0 (0.00 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Unshaded rays: 0 (0.00 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Light cache utilization: 99.88%
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Number of light evaluations: 6326023 (6.86 per pixel)
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Maximum memory usage for resman: 0.94 MB
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Number of intersectable primitives: 4
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: SD triangles: 2
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: MB triangles: 0
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Static primitives: 2
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Moving primitives: 0
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Infinite primitives: 0
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Static hair segments: 0
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Moving hair segments: 0
2019-11-12 15:11:50:  0: STDOUT: VFH [Info] V-Ray: Maximum memory usage for texman: 0.00 MB
2019-11-12 15:11:50:  0: STDOUT: Finished Rendering
2019-11-12 15:11:51:  0: INFO: Process exit code: 0
2019-11-12 15:11:51:  0: INFO: Finished Houdini Job
2019-11-12 15:11:51:  0: Done executing plugin command of type 'Render Task'

=======================================================
Details
=======================================================
Date: 11/12/2019 15:11:52
Frames: 1
Job Submit Date: 11/12/2019 15:10:11
Job User: jdechent
Average RAM Usage: 366273408 (1%)
Peak RAM Usage: 948518912 (2%)
Average CPU Usage: 4%
Peak CPU Usage: 33%
Used CPU Clocks (x10^6 cycles): 60471
Total CPU Clocks (x10^6 cycles): 1511770

=======================================================
Slave Information
=======================================================
Slave Name: RENDER39
Version: v10.0.27.2 Release (37e76e772)
Operating System: Windows 10 Pro
Running As Service: No
Machine User: render
IP Address: 172.16.100.239
MAC Address: B8:CA:3A:6B:88:60
CPU Architecture: x64
CPUs: 64
CPU Usage: 4%
Memory Usage: 5.4 GB / 63.9 GB (8%)
Free Disk Space: 119.210 GB 
Video Card: Microsoft Basic Display Adapter