=======================================================
Log
2024-06-20 12:49:55: 0: Loading Job’s Plugin timeout is Disabled
2024-06-20 12:49:55: 0: SandboxedPlugin: Render Job As User disabled, running as current user ‘pausan’
2024-06-20 12:49:57: 0: Executing plugin command of type ‘Initialize Plugin’
2024-06-20 12:49:57: 0: INFO: Executing plugin script ‘C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\plugins\667487bb222792e823593cfb\bsvfx_houdini.py’
2024-06-20 12:49:57: 0: INFO: Plugin execution sandbox using Python version 3
2024-06-20 12:49:57: 0: INFO: About: Houdini Plugin for Deadline
2024-06-20 12:49:57: 0: INFO: The job’s environment will be merged with the current environment before rendering
2024-06-20 12:49:57: 0: Done executing plugin command of type ‘Initialize Plugin’
2024-06-20 12:49:57: 0: Start Job timeout is disabled.
2024-06-20 12:49:57: 0: Task timeout is disabled.
2024-06-20 12:49:57: 0: Loaded job: dev_102_040_1000_fx_main_default_v014 - /obj/geo1/squaby/ropnet/geometry1 (667487bb222792e823593cfb)
2024-06-20 12:49:57: 0: Skipping Y: because it is already mapped
2024-06-20 12:49:57: 0: Skipping Z: because it is already mapped
2024-06-20 12:49:57: 0: Skipping T: because it is already mapped
2024-06-20 12:49:57: 0: Skipping P: because it is already mapped
2024-06-20 12:49:57: 0: Executing plugin command of type ‘Start Job’
2024-06-20 12:49:57: 0: DEBUG: S3BackedCache Client is not installed.
2024-06-20 12:49:57: 0: INFO: Executing global asset transfer preload script ‘C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\plugins\667487bb222792e823593cfb\GlobalAssetTransferPreLoad.py’
2024-06-20 12:49:57: 0: INFO: Looking for legacy (pre-10.0.26) AWS Portal File Transfer…
2024-06-20 12:49:57: 0: INFO: Looking for legacy (pre-10.0.26) File Transfer controller in C:/Program Files/Thinkbox/S3BackedCache/bin/task.py…
2024-06-20 12:49:57: 0: INFO: Could not find legacy (pre-10.0.26) AWS Portal File Transfer.
2024-06-20 12:49:57: 0: INFO: Legacy (pre-10.0.26) AWS Portal File Transfer is not installed on the system.
2024-06-20 12:49:57: 0: Done executing plugin command of type ‘Start Job’
2024-06-20 12:49:57: 0: Plugin rendering frame(s): 1001-1010
2024-06-20 12:49:58: 0: Executing plugin command of type ‘Render Task’
2024-06-20 12:49:58: 0: INFO: Starting Houdini Job
2024-06-20 12:49:58: 0: INFO: Stdout Redirection Enabled: True
2024-06-20 12:49:58: 0: INFO: Stdout Handling Enabled: True
2024-06-20 12:49:58: 0: INFO: Popup Handling Enabled: True
2024-06-20 12:49:58: 0: INFO: QT Popup Handling Enabled: False
2024-06-20 12:49:58: 0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2024-06-20 12:49:58: 0: INFO: Using Process Tree: True
2024-06-20 12:49:58: 0: INFO: Hiding DOS Window: True
2024-06-20 12:49:58: 0: INFO: Creating New Console: False
2024-06-20 12:49:58: 0: INFO: Running as user: pausan
2024-06-20 12:49:58: 0: PYTHON: avr_cmd not found in environment variables. Using default path.
2024-06-20 12:49:58: 0: INFO: Executable: “//prod.barnstormvfx.com/pipeline/avr/avr.cmd”
2024-06-20 12:49:58: 0: INFO: Argument: --use-env houdini_hython “C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\plugins\667487bb222792e823593cfb\hrender_dl.py” -f 1001 1010 1 -o “Y:/projects/dev/102/040/1000/fx_main/default/publishes/squaby/v014/dev_102_040_1000_fx_main_default_squaby_v014.$F4.bgeosc” -g -d /obj/geo1/squaby/ropnet/geometry1 -gpu 4,5,6,7,8,9,10,11,12,13,14,15 -tempdir “C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\jobsData\667487bb222792e823593cfb\0_tempgv1b10” -arnoldAbortOnLicenseFail 1 “Y:/projects/dev/102/040/1000/fx_main/default/publishes/houdiniScene/v014/dev_102_040_1000_fx_main_default_v014_20240620124903.hip”
2024-06-20 12:49:58: 0: INFO: Full Command: “//prod.barnstormvfx.com/pipeline/avr/avr.cmd” --use-env houdini_hython “C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\plugins\667487bb222792e823593cfb\hrender_dl.py” -f 1001 1010 1 -o “Y:/projects/dev/102/040/1000/fx_main/default/publishes/squaby/v014/dev_102_040_1000_fx_main_default_squaby_v014.$F4.bgeosc” -g -d /obj/geo1/squaby/ropnet/geometry1 -gpu 4,5,6,7,8,9,10,11,12,13,14,15 -tempdir “C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\jobsData\667487bb222792e823593cfb\0_tempgv1b10” -arnoldAbortOnLicenseFail 1 “Y:/projects/dev/102/040/1000/fx_main/default/publishes/houdiniScene/v014/dev_102_040_1000_fx_main_default_v014_20240620124903.hip”
2024-06-20 12:49:58: 0: INFO: Startup Directory: “\prod.barnstormvfx.com\pipeline\avr”
2024-06-20 12:49:58: 0: INFO: Process Priority: BelowNormal
2024-06-20 12:49:58: 0: INFO: Process Affinity: default
2024-06-20 12:49:58: 0: INFO: Process is now running
2024-06-20 12:49:58: 0: STDOUT: ‘\prod.barnstormvfx.com\pipeline\avr’
2024-06-20 12:49:58: 0: STDOUT: CMD.EXE was started with the above path as the current directory.
2024-06-20 12:49:58: 0: STDOUT: UNC paths are not supported. Defaulting to Windows directory.
2024-06-20 12:50:03: 0: STDOUT: © 2024 Barnstorm VFX. Aviator v0.3.3. All rights reserved.
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | log started Thu Jun 20 12:50:07 2024
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | Arnold 7.2.5.1 [fd213eeb] windows x86_64 clang-15.0.7 oiio-2.4.1 osl-1.13.0 vdb-7.1.1 adlsdk-7.4.2.47 clmhub-3.1.1.43 rlm-14.2.5 optix-8.0.0 2024/01/12 02:53:40
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | running on BARNSTORM-94, pid=28312
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | 1 x Intel(R) Core™ i9-10900K CPU @ 3.70GHz (10 cores, 20 logical) with 130992MB
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | NVIDIA driver version 536.67
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | GPU 0: NVIDIA GeForce RTX 3080 @ 1740MHz (compute 8.6) with 10239MB (3704MB available) (NVLink:0)
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | Windows 10 (version 10.0, build 19041)
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB | soft limit for open files changed from 8192 to 2048
2024-06-20 12:50:07: 0: STDOUT: 00:00:00 272MB |
2024-06-20 12:50:08: 0: STDOUT: 00:00:01 415MB | [color_manager_ocio] default ocio.config found in Y:\Resources\Arnold\htoa-6.2.5.1\htoa-6.2.5.1_r539f2c5_houdini-20.0.547\scripts\bin…/ocio/configs/arnold/config.ocio
2024-06-20 12:50:08: 0: STDOUT: 00:00:01 415MB | loading plugins from Y:\Resources\Arnold\htoa-6.2.5.1\htoa-6.2.5.1_r539f2c5_houdini-20.0.547\scripts\bin…\plugins …
2024-06-20 12:50:08: 0: STDOUT: 00:00:01 416MB | cryptomatte.dll: cryptomatte uses Arnold 7.2.5.1
2024-06-20 12:50:08: 0: STDOUT: 00:00:01 416MB | cryptomatte.dll: cryptomatte_filter uses Arnold 7.2.5.1
2024-06-20 12:50:08: 0: STDOUT: 00:00:01 416MB | cryptomatte.dll: cryptomatte_manifest_driver uses Arnold 7.2.5.1
2024-06-20 12:50:08: 0: STDOUT: 00:00:01 416MB | htoa_mandelbulb_proc.dll: mandelbulb uses Arnold 7.2.5.1
2024-06-20 12:50:09: 0: STDOUT: 00:00:01 430MB | usd_proc.dll: usd uses Arnold 7.2.5.1
2024-06-20 12:50:09: 0: STDOUT: 00:00:01 431MB | alembic_proc.dll: alembic uses Arnold 7.2.5.1
2024-06-20 12:50:09: 0: STDOUT: 00:00:02 431MB | loaded 6 plugins from 4 lib(s) in 0:00.87
2024-06-20 12:50:09: 0: STDOUT: 00:00:02 438MB | [htoa_op] Begin registration …
2024-06-20 12:50:09: 0: STDOUT: 00:00:02 433MB | [obj_procedurals] Registering procedurals …
2024-06-20 12:50:09: 0: STDOUT: 00:00:02 433MB | [obj_procedurals] procedural registration done.
2024-06-20 12:50:09: 0: STDOUT: 00:00:02 464MB | [rop_operators] Registering operators …
2024-06-20 12:50:09: 0: STDOUT: 00:00:02 464MB | [rop_operators] operator registration done.
2024-06-20 12:50:13: 0: STDOUT: 00:00:05 504MB | [vop_shaders] Registering shaders …
2024-06-20 12:50:13: 0: STDOUT: 00:00:05 508MB | [vop_shaders] shader registration done.
2024-06-20 12:50:13: 0: STDOUT: 00:00:05 508MB | [vop_imagers] Registering imagers …
2024-06-20 12:50:13: 0: STDOUT: 00:00:06 509MB | [vop_imagers] imager registration done.
2024-06-20 12:50:13: 0: STDOUT: 00:00:06 509MB | [htoa_op] USD shader translation registration
2024-06-20 12:50:14: 0: STDOUT: 00:00:06 527MB | [htoa_op] End registration.
2024-06-20 12:50:14: 0: STDOUT: 00:00:06 527MB |
2024-06-20 12:50:14: 0: STDOUT: 00:00:06 527MB | releasing resources
2024-06-20 12:50:14: 0: STDOUT: 00:00:06 513MB | Arnold shutdown
2024-06-20 12:51:23: 0: STDOUT: Detected Houdini version: (20, 0, 547)
2024-06-20 12:51:23: 0: STDOUT: [‘C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\plugins\667487bb222792e823593cfb\hrender_dl.py’, ‘-f’, ‘1001’, ‘1010’, ‘1’, ‘-o’, ‘Y:/projects/dev/102/040/1000/fx_main/default/publishes/squaby/v014/dev_102_040_1000_fx_main_default_squaby_v014…bgeosc’, ‘-g’, ‘-d’, ‘/obj/geo1/squaby/ropnet/geometry1’, ‘-gpu’, ‘4,5,6,7,8,9,10,11,12,13,14,15’, ‘-tempdir’, ‘C:\ProgramData\Thinkbox\Deadline10\workers\barnstorm-94\jobsData\667487bb222792e823593cfb\0_tempgv1b10’, ‘-arnoldAbortOnLicenseFail’, ‘1’, ‘Y:/projects/dev/102/040/1000/fx_main/default/publishes/houdiniScene/v014/dev_102_040_1000_fx_main_default_v014_20240620124903.hip’]
2024-06-20 12:51:23: 0: STDOUT: Start: 1001
2024-06-20 12:51:23: 0: STDOUT: End: 1010
2024-06-20 12:51:23: 0: STDOUT: Increment: 1
2024-06-20 12:51:23: 0: STDOUT: Ignore Inputs: True
2024-06-20 12:51:23: 0: STDOUT: ----------------------------------------------------------------------------------------------------
2024-06-20 12:51:23: 0: STDOUT: BSVFX TEST
2024-06-20 12:51:23: 0: STDOUT: Output: Y:/projects/dev/102/040/1000/fx_main/default/publishes/squaby/v014/dev_102_040_1000_fx_main_default_squaby_v014…bgeosc
2024-06-20 12:51:23: 0: STDOUT: GPUs: 4,5,6,7,8,9,10,11,12,13,14,15
2024-06-20 12:51:23: 0: STDOUT: Driver: /obj/geo1/squaby/ropnet/geometry1
2024-06-20 12:51:23: 0: STDOUT: Input File: Y:/projects/dev/102/040/1000/fx_main/default/publishes/houdiniScene/v014/dev_102_040_1000_fx_main_default_v014_20240620124903.hip
2024-06-20 12:51:28: 0: STDOUT: 2024-06-20 12:51:28.541 | DEBUG | bsvfx_radar.framework.context:_get_context_from_env:236 - RadarContext initiated from AVR:
2024-06-20 12:51:28: 0: STDOUT: project_abbr=‘dev’ project_sgid=9043 job_step=‘fx’ job_descriptor=‘main’ step_sgid=8 job_detail=‘default’ episode=‘102’ episode_sgid=471 sequence=‘040’ sequence_sgid=25303 shot=‘1000’ shot_sgid=265425
2024-06-20 12:51:33: 0: STDOUT: Begin Path Mapping
2024-06-20 12:51:35: 0: STDOUT: b’’
2024-06-20 12:51:35: 0: STDOUT: b’’
2024-06-20 12:51:35: 0: STDOUT: End Path Mapping
2024-06-20 12:51:35: 0: STDOUT: ROP type: geometry
2024-06-20 12:51:35: 0: STDOUT: Enabled Alfred style progress
2024-06-20 12:51:35: 0: STDOUT: Enabled network use reporting
2024-06-20 12:51:35: 0: STDOUT: Rendering frame 1001 to 1010
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.6667
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 0%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.7083
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 11%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.75
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 22%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.7917
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 33%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.8333
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 44%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.875
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 56%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.9167
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 67%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 41.9583
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 78%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 42
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 89%
2024-06-20 12:51:36: 0: STDOUT: geometry1 at time 42.0417
2024-06-20 12:51:36: 0: STDOUT: Bytes sent: 0
2024-06-20 12:51:36: 0: STDOUT: Bytes received: 0
2024-06-20 12:51:36: 0: STDOUT: Seconds taken: 0
2024-06-20 12:51:36: 0: STDOUT: ALF_PROGRESS 100%
2024-06-20 12:51:37: 0: STDOUT: Finished Rendering
2024-06-20 12:51:37: 0: INFO: Process exit code: 0
2024-06-20 12:51:37: 0: INFO: Finished Houdini Job
2024-06-20 12:51:37: 0: Done executing plugin command of type ‘Render Task’
=======================================================
Details
Date: 06/20/2024 12:51:38
Frames: 1001-1010
Job Submit Date: 06/20/2024 12:49:15
Job User: praran
Average RAM Usage: 745452800 (1%)
Peak RAM Usage: 1115140096 (1%)
Average CPU Usage: 2%
Peak CPU Usage: 4%
Used CPU Clocks (x10^6 cycles): 114835
Total CPU Clocks (x10^6 cycles): 5741736
=======================================================
Worker Information
Worker Name: BARNSTORM-94
Version: v10.2.0.10 Release (3b87216c7)
Operating System: Windows 10 Pro
Running As Service: No
Machine User: pausan
CPU Architecture: x64
CPUs: 20
CPU Usage: 7%
Memory Usage: 41.1 GB / 127.9 GB (32%)
Free Disk Space: 683.848 GB (118.694 GB on C:, 565.154 GB on D:)
Video Card: NVIDIA GeForce RTX 3080