Deadline would complete one task and others would stall with the following: Skipping thermal shutdown check because it is not required at this time.
Connecting to gb1-04...
2018-08-05 14:36:37: 0: STDOUT: [14:36:37] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainC_surface_DISP_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:36:37: 0: STDOUT: [14:36:37] Building octree
2018-08-05 14:36:37: 0: STDOUT: [14:36:37] Creating geometry (/obj/hero_asteroid_sim6)
2018-08-05 14:36:38: 0: STDOUT: [14:36:37] Creating displaced geometry (/obj/hero_asteroid_sim6)
2018-08-05 14:36:38: 0: STDOUT: [14:36:37] Building octree
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Creating geometry (/obj/hero_asteroid_sim6_split1)
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Creating displaced geometry (/obj/hero_asteroid_sim6_split1)
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Converting texture 'Z:/jobs/sol/sequences/ori/ori_020/fx/fx/work/houdini/tex/sol_planetRingOri_alpha_v001.jpg' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Building octree
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Building octree
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Building octree
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Building octree
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Building octree
2018-08-05 14:36:38: 0: STDOUT: [14:36:38] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Converting texture 'Z:/LIU/05_animation/sequences/22_had/art/working/sol_ori_010_previs_v0005/tex/hadean_col_20180205.png' to .rat format. This uses approximately 170.75 MB of extra memory.
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: ALF_PROGRESS 3%
2018-08-05 14:36:47: 0: STDOUT: [14:36:47] Building octree
2018-08-05 14:36:47: 0: STDOUT: ALF_PROGRESS 4%
2018-08-05 14:36:48: 0: STDOUT: [14:36:48] Converting texture 'Z:/jobs/sol/sequences/ori/ori_020/fx/fx/work/houdini/tex/sol_planetRingOri_lum_v001.jpg' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:36:54: 0: STDOUT: [14:36:54] Converting texture 'Z:/LIU/05_animation/sequences/22_had/art/working/sol_ori_010_previs_v0005/tex/hadean_lum_20180205.png' to .rat format. This uses approximately 170.75 MB of extra memory.
2018-08-05 14:36:59: 0: STDOUT: [14:36:59] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteorOri_mainA_surface_NORM_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:04: 0: STDOUT: [14:37:04] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteorOri_mainA_surface_DIFF_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:04: 0: STDOUT: [14:37:04] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainE_surface_NORM_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:08: 0: STDOUT: [14:37:08] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteorOri_mainA_surface_SPECr_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:08: 0: STDOUT: ALF_PROGRESS 5%
2018-08-05 14:37:09: 0: STDOUT: [14:37:09] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainE_surface_DIFF_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:09: 0: STDOUT: ALF_PROGRESS 6%
2018-08-05 14:37:10: 0: STDOUT: [14:37:10] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainE_surface_SPECr_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:10: 0: STDOUT: ALF_PROGRESS 7%
2018-08-05 14:37:10: 0: STDOUT: ALF_PROGRESS 8%
2018-08-05 14:37:10: 0: STDOUT: [14:37:10] Building octree
2018-08-05 14:37:11: 0: STDOUT: [14:37:11] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteorOri_mainA_surface_SPEC_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:11: 0: STDOUT: ALF_PROGRESS 9%
2018-08-05 14:37:12: 0: STDOUT: [14:37:12] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainE_surface_SPEC_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:15: 0: STDOUT: [14:37:14] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainC_surface_DIFF_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:15: 0: STDOUT: [14:37:15] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainC_surface_NORM_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:15: 0: STDOUT: ALF_PROGRESS 10%
2018-08-05 14:37:16: 0: STDOUT: [14:37:16] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainB_surface_NORM_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:19: 0: STDOUT: [14:37:19] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainC_surface_SPECr_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:19: 0: STDOUT: [14:37:19] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainB_surface_DIFF_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:20: 0: STDOUT: [14:37:19] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainD_surface_NORM_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:20: 0: STDOUT: ALF_PROGRESS 11%
2018-08-05 14:37:24: 0: STDOUT: [14:37:24] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainB_surface_SPECr_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:24: 0: STDOUT: ALF_PROGRESS 12%
2018-08-05 14:37:24: 0: STDOUT: [14:37:24] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainD_surface_DIFF_v001.png' to .rat format. This uses approximately 85.34 MB of extra memory.
2018-08-05 14:37:25: 0: STDOUT: ALF_PROGRESS 13%
2018-08-05 14:37:25: 0: STDOUT: [14:37:25] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainC_surface_SPEC_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:26: 0: STDOUT: [14:37:26] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainD_surface_SPECr_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:26: 0: STDOUT: ALF_PROGRESS 14%
2018-08-05 14:37:28: 0: STDOUT: [14:37:27] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainA_surface_SPEC_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:28: 0: STDOUT: ALF_PROGRESS 15%
2018-08-05 14:37:28: 0: STDOUT: ALF_PROGRESS 16%
2018-08-05 14:37:29: 0: STDOUT: [14:37:29] Converting texture 'Z:/jobs/sol/assets/fx/meteorOri/tex/texture/work/designer/export/meteroOri_mainD_surface_SPEC_v001.png' to .rat format. This uses approximately 42.67 MB of extra memory.
2018-08-05 14:37:29: 0: STDOUT: ALF_PROGRESS 17%
2018-08-05 14:37:29: 0: STDOUT: ALF_PROGRESS 18%
2018-08-05 14:37:29: 0: STDOUT: ALF_PROGRESS 19%
2018-08-05 14:37:30: 0: STDOUT: ALF_PROGRESS 20%
2018-08-05 14:37:30: 0: STDOUT: ALF_PROGRESS 21%
2018-08-05 14:37:30: 0: STDOUT: ALF_PROGRESS 22%
2018-08-05 14:37:30: 0: STDOUT: ALF_PROGRESS 23%
2018-08-05 14:37:31: 0: STDOUT: ALF_PROGRESS 24%
2018-08-05 14:37:31: 0: STDOUT: ALF_PROGRESS 25%
2018-08-05 14:37:31: 0: STDOUT: ALF_PROGRESS 26%
2018-08-05 14:37:32: 0: STDOUT: ALF_PROGRESS 27%
2018-08-05 14:37:32: 0: STDOUT: ALF_PROGRESS 28%
2018-08-05 14:37:32: 0: STDOUT: ALF_PROGRESS 29%
2018-08-05 14:37:33: 0: STDOUT: ALF_PROGRESS 30%
2018-08-05 14:37:33: 0: STDOUT: ALF_PROGRESS 31%
2018-08-05 14:37:33: 0: STDOUT: ALF_PROGRESS 32%
2018-08-05 14:37:34: 0: STDOUT: ALF_PROGRESS 33%
2018-08-05 14:37:34: 0: STDOUT: ALF_PROGRESS 34%
2018-08-05 14:37:34: 0: STDOUT: ALF_PROGRESS 35%
2018-08-05 14:37:35: 0: STDOUT: ALF_PROGRESS 36%
2018-08-05 14:37:35: 0: STDOUT: ALF_PROGRESS 37%
2018-08-05 14:37:35: 0: STDOUT: ALF_PROGRESS 38%
2018-08-05 14:37:36: 0: STDOUT: ALF_PROGRESS 39%
2018-08-05 14:37:36: 0: STDOUT: ALF_PROGRESS 40%
2018-08-05 14:37:37: 0: STDOUT: ALF_PROGRESS 41%
2018-08-05 14:37:37: 0: STDOUT: ALF_PROGRESS 42%
2018-08-05 14:37:38: 0: STDOUT: ALF_PROGRESS 43%
2018-08-05 14:37:38: 0: STDOUT: ALF_PROGRESS 44%
2018-08-05 14:37:39: 0: STDOUT: ALF_PROGRESS 45%
2018-08-05 14:37:39: 0: STDOUT: ALF_PROGRESS 46%
2018-08-05 14:37:40: 0: STDOUT: ALF_PROGRESS 47%
2018-08-05 14:37:40: 0: STDOUT: ALF_PROGRESS 48%
2018-08-05 14:37:41: 0: STDOUT: ALF_PROGRESS 49%
2018-08-05 14:37:41: 0: STDOUT: ALF_PROGRESS 50%
2018-08-05 14:37:42: 0: STDOUT: ALF_PROGRESS 51%
2018-08-05 14:37:42: 0: STDOUT: ALF_PROGRESS 52%
2018-08-05 14:37:43: 0: STDOUT: ALF_PROGRESS 53%
2018-08-05 14:37:44: 0: STDOUT: ALF_PROGRESS 54%
2018-08-05 14:37:44: 0: STDOUT: ALF_PROGRESS 55%
2018-08-05 14:37:45: 0: STDOUT: ALF_PROGRESS 56%
2018-08-05 14:37:45: 0: STDOUT: ALF_PROGRESS 57%
2018-08-05 14:37:46: 0: STDOUT: ALF_PROGRESS 58%
2018-08-05 14:37:47: 0: STDOUT: ALF_PROGRESS 59%
2018-08-05 14:37:47: 0: STDOUT: ALF_PROGRESS 60%
2018-08-05 14:37:48: 0: STDOUT: ALF_PROGRESS 61%
2018-08-05 14:37:49: 0: STDOUT: ALF_PROGRESS 62%
2018-08-05 14:37:49: 0: STDOUT: ALF_PROGRESS 63%
2018-08-05 14:37:50: 0: STDOUT: ALF_PROGRESS 64%
2018-08-05 14:37:50: 0: STDOUT: ALF_PROGRESS 65%
2018-08-05 14:37:51: 0: STDOUT: ALF_PROGRESS 66%
2018-08-05 14:37:51: 0: STDOUT: ALF_PROGRESS 67%
2018-08-05 14:37:52: 0: STDOUT: ALF_PROGRESS 68%
2018-08-05 14:37:53: 0: STDOUT: ALF_PROGRESS 69%
2018-08-05 14:37:53: 0: STDOUT: ALF_PROGRESS 70%
2018-08-05 14:37:54: 0: STDOUT: ALF_PROGRESS 71%
2018-08-05 14:37:55: 0: STDOUT: ALF_PROGRESS 72%
2018-08-05 14:37:55: 0: STDOUT: ALF_PROGRESS 73%
2018-08-05 14:37:56: 0: STDOUT: ALF_PROGRESS 74%
2018-08-05 14:37:56: 0: STDOUT: ALF_PROGRESS 75%
2018-08-05 14:37:57: 0: STDOUT: ALF_PROGRESS 76%
2018-08-05 14:37:57: 0: STDOUT: ALF_PROGRESS 77%
2018-08-05 14:37:58: 0: STDOUT: ALF_PROGRESS 78%
2018-08-05 14:37:58: 0: STDOUT: ALF_PROGRESS 79%
2018-08-05 14:37:59: 0: STDOUT: ALF_PROGRESS 80%
2018-08-05 14:38:01: 0: STDOUT: ALF_PROGRESS 81%
2018-08-05 14:38:02: 0: STDOUT: ALF_PROGRESS 82%
2018-08-05 14:38:04: 0: STDOUT: ALF_PROGRESS 83%
2018-08-05 14:38:06: 0: STDOUT: ALF_PROGRESS 84%
2018-08-05 14:38:08: 0: STDOUT: ALF_PROGRESS 85%
2018-08-05 14:38:09: 0: STDOUT: ALF_PROGRESS 86%
2018-08-05 14:38:11: 0: STDOUT: ALF_PROGRESS 87%
2018-08-05 14:38:13: 0: STDOUT: ALF_PROGRESS 88%
2018-08-05 14:38:15: 0: STDOUT: ALF_PROGRESS 89%
2018-08-05 14:38:17: 0: STDOUT: ALF_PROGRESS 90%
2018-08-05 14:38:19: 0: STDOUT: ALF_PROGRESS 91%
2018-08-05 14:38:21: 0: STDOUT: ALF_PROGRESS 92%
2018-08-05 14:38:22: 0: STDOUT: ALF_PROGRESS 93%
2018-08-05 14:38:24: 0: STDOUT: ALF_PROGRESS 94%
2018-08-05 14:38:24: 0: STDOUT: ALF_PROGRESS 95%
2018-08-05 14:38:25: 0: STDOUT: ALF_PROGRESS 96%
2018-08-05 14:38:25: 0: STDOUT: ALF_PROGRESS 97%
2018-08-05 14:38:25: 0: STDOUT: ALF_PROGRESS 98%
2018-08-05 14:38:25: 0: STDOUT: ALF_PROGRESS 99%
2018-08-05 14:38:25: 0: STDOUT: ALF_PROGRESS 100%
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] Threading Efficiency: 4791.50%
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] Ray Counts:
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] 16,810,000 primary rays 4.01 rays/pixel
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] 33,422,541 occlusion rays 7.97 rays/pixel
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] 34,479,159 opacity rays 8.22 rays/pixel
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] 16,403,422 shading rays 3.91 rays/pixel
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] 101,115,122 total rays 24.11 rays/pixel
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] Unified Cache: 771.37 MB of 23.99 GB used
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] In Cache Faults Generated Item Size
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] Procedural: 771.37 MB 7 771.37 MB 110.20 MB
2018-08-05 14:38:25: 0: STDOUT: [14:38:25] 31 non-RAT image conversions using 2.76 GB
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: /obj/hero_meteor was displaced 0.0353579 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim1 was displaced 0.0524158 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim1 (/obj/hero_asteroid_sim1_split1) was displaced 0.0432968 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim2 was displaced 0.0626863 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim2 (/obj/hero_asteroid_sim2_split1) was displaced 0.053311 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim3 was displaced 0.0563452 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim3 (/obj/hero_asteroid_sim3_split1) was displaced 0.0486153 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim4 was displaced 0.068693 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim4 (/obj/hero_asteroid_sim4_split1) was displaced 0.0861146 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim6 was displaced 0.0728534 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:26] mantra: WARNING: A procedural of /obj/hero_asteroid_sim6 (/obj/hero_asteroid_sim6_split1) was displaced 0.0571918 (bounds are 0.025 in world space)
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] Frame Wall Clock Time: 0:02:40.75
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] Total Wall Clock Time: 0:04:25.79
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] Total CPU Time: 0:01:02.84
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] System CPU Time Only: 0:00:14.84
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] Peak Memory Usage: 10.69 GB
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] Peak Geometry Objects: 38
2018-08-05 14:38:27: 0: STDOUT: [14:38:27] VEX module cache misses: 0
2018-08-05 14:38:29: 0: STDOUT: Finished Rendering
2018-08-05 14:38:30: 0: INFO: Process exit code: 0
2018-08-05 14:38:30: 0: INFO: Finished Houdini Job
2018-08-05 14:38:30: 0: Done executing plugin command of type 'Render Task'
2018-08-05 14:38:30: 0: Render time for frame(s): 23.319 m
2018-08-05 14:38:30: 0: Total time for task: 23.435 m
2018-08-05 14:38:31: 0: Saving task log...
2018-08-05 14:38:31: Scheduler Thread - Render Thread 0 completed its task
2018-08-05 14:38:31: Scheduler Thread - Seconds before next job scan: 1
2018-08-05 14:38:32: Scheduler Thread - Performing pending job scan...
2018-08-05 14:38:32: Performing pending job scan
2018-08-05 14:38:32: Pending Job Scan - Loading pending and active jobs
2018-08-05 14:38:32: Pending Job Scan - Loaded 12 pending and active jobs in 15.625 ms
2018-08-05 14:38:32: Pending Job Scan - Scanning pending and active jobs
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/small_planet_impacts" (5b6517df1405a62c84a00af3)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/frame" (5b6517dc1405a6145877d060)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/asteroid_field" (5b6517d11405a6406c4f040e)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/expl_cone" (5b6517d91405a624a8a2aa62)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/equatorial_fine_debris" (5b6517d41405a64300242a0d)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_sat_030_prev_previs_main_v012 - camMAIN" (5b64d9ec1405ad12e437c3ce)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/hero_meteor" (5b6517cf1405a64e649d4a42)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/bg_starfield" (5b6517d71405a63ee4b6a761)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/orion_all" (5b6517aa1405a6552470dc2c)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v013 - /obj/ropnet1/orion" (5b6517cc1405a65430b14741)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_ori_020_fx_fx_main_v012 - /obj/ropnet1/orion_distant" (5b64f1301405a64d9c450bdf)
2018-08-05 14:38:32: Pending Job Scan - Scanning Job "sol_low_020_prev_previs_main_v025 - camMain" (5b650b431405a84b7002e2ba)
2018-08-05 14:38:32: Pending Job Scan - Released 0 pending jobs and 0 pending tasks in 0.000 s
2018-08-05 14:38:32: Pending Job Scan - Done.
2018-08-05 14:38:32: Processing Pending Job Events
2018-08-05 14:38:32: Pending Job Events - Checking for pending job events...
2018-08-05 14:38:32: Pending Job Events - Processing 0 job events
2018-08-05 14:38:32: Pending Job Events - No more job events to process
2018-08-05 14:38:32: Pending Job Events - Done.
2018-08-05 14:38:32: Scheduler Thread - Performing repository repair...
2018-08-05 14:38:32: Performing repository repair
2018-08-05 14:38:32: Performing Orphaned Task Scan...
2018-08-05 14:38:32: Orphaned Task Scan - Loading rendering jobs
2018-08-05 14:38:32: Orphaned Task Scan - Loaded 3 rendering jobs in 31.250 ms
2018-08-05 14:38:32: Orphaned Task Scan - Scanning for orphaned tasks
2018-08-05 14:38:32: Orphaned Task Scan - Separated jobs into 1 lists of 100
2018-08-05 14:38:32: Orphaned Task Scan - Scanning job list 1 of 1 (3 jobs)
2018-08-05 14:38:33: Orphaned Task Scan - Cleaned up 0 orphaned tasks in 421.874 ms
2018-08-05 14:38:33: Orphaned Task Scan - Done.
2018-08-05 14:38:33: Performing Orphaned Limit Stub Scan...
2018-08-05 14:38:33: Orphaned Limit Stub Scan - Loading limits
2018-08-05 14:38:33: Orphaned Limit Stub Scan - Loaded 338 limits in 15.625 ms
2018-08-05 14:38:33: Orphaned Limit Stub Scan - Loading slave states
2018-08-05 14:38:33: Orphaned Limit Stub Scan - Loaded 54 slave states in 0.000 s
2018-08-05 14:38:33: Orphaned Limit Stub Scan - Returned 0 orphaned limit stubs in 109.375 ms
2018-08-05 14:38:33: Orphaned Limit Stub Scan - Done.
2018-08-05 14:38:33: Checking Available Database Connections
2018-08-05 14:38:33: Available Database Connections - Checking available database connections
2018-08-05 14:38:33: Available Database Connections - Found 999523 available connections in 15.625 ms
2018-08-05 14:38:33: Available Database Connections - Done.
2018-08-05 14:38:33: Performing Stalled Slave Scan...
2018-08-05 14:38:33: Stalled Slave Scan - Loading slave states
2018-08-05 14:38:33: Stalled Slave Scan - Loaded 54 slave states in 0.000 s
2018-08-05 14:38:33: Stalled Slave Scan - Scanning slave states
2018-08-05 14:38:33: Stalled Slave Scan - Cleaned up 0 stalled slaves in 0.000 s
2018-08-05 14:38:33: Stalled Slave Scan - Done.
2018-08-05 14:38:33: Performing Stalled Pulse Scan...
2018-08-05 14:38:33: Stalled Pulse Scan - Loading pulse states
2018-08-05 14:38:33: Stalled Pulse Scan - Loaded 1 pulse states in 0.000 s
2018-08-05 14:38:33: Stalled Pulse Scan - Scanning pulse states
2018-08-05 14:38:33: Stalled Pulse Scan - Cleaned up 0 stalled pulses in 0.000 s
2018-08-05 14:38:33: Stalled Pulse Scan - Done.
2018-08-05 14:38:33: Performing Stalled Balancer Scan...
2018-08-05 14:38:33: Stalled Balancer Scan - Loading balancer states
2018-08-05 14:38:33: Stalled Balancer Scan - Loaded 1 balancer states in 0.000 s
2018-08-05 14:38:33: Stalled Balancer Scan - Scanning balancer states
2018-08-05 14:38:33: Stalled Balancer Scan - Cleaned up 0 stalled balancers in 0.000 s
2018-08-05 14:38:33: Stalled Balancer Scan - Done.
2018-08-05 14:38:33: Performing Stalled License Forwarder Scan...
2018-08-05 14:38:33: Stalled License Forwarder Scan - Loading forwarder states
2018-08-05 14:38:33: Stalled License Forwarder Scan - Loaded 0 forwarder states in 0.000 s
2018-08-05 14:38:33: Stalled License Forwarder Scan - Done.
2018-08-05 14:38:33: Performing Stalled Connection Server Scan...
2018-08-05 14:38:33: Stalled Connection Server Scan - Loading Connection Server states
2018-08-05 14:38:33: Stalled Connection Server Scan - Loaded 0 Connection Server states in 0.000 s
2018-08-05 14:38:33: Stalled Connection Server Scan - Done.
2018-08-05 14:38:33: Triggering repository repair events...
2018-08-05 14:38:33: Scheduler Thread - Performing house cleaning...
2018-08-05 14:38:33: Performing house cleaning
2018-08-05 14:38:33: Performing Job Cleanup Scan...
2018-08-05 14:38:33: Job Cleanup Scan - Loading completed jobs
2018-08-05 14:38:34: Job Cleanup Scan - Loaded 307 completed and 12 active/pending jobs in 562.498 ms
2018-08-05 14:38:34: Job Cleanup Scan - Scanning completed jobs
2018-08-05 14:38:34: Job Cleanup Scan - Deleted 0 and archived 0 completed jobs in 0.000 s
2018-08-05 14:38:34: Job Cleanup Scan - Done.
2018-08-05 14:38:34: Purging Unsubmitted Jobs
2018-08-05 14:38:34: Unsubmitted Job Scan - Loading unsubmitted jobs
2018-08-05 14:38:34: Unsubmitted Job Scan - Loaded 0 unsubmitted jobs in 0.000 s
2018-08-05 14:38:34: Unsubmitted Job Scan - Done.
2018-08-05 14:38:34: Purging Deleted Jobs
2018-08-05 14:38:34: Deleted Job Scan - Loading deleted jobs
2018-08-05 14:38:34: Deleted Job Scan - Loaded 0 deleted jobs in 0.000 s
2018-08-05 14:38:34: Deleted Job Scan - Done.
2018-08-05 14:38:34: Purging Obsolete Slaves
2018-08-05 14:38:34: Obsolete Slave Scan - Skipping because it is disabled in the Repository Options
2018-08-05 14:38:34: Purging Old Slave Reports
2018-08-05 14:38:34: Slave Report Scan - Loading slave report collections
2018-08-05 14:38:34: Slave Report Scan - Found 54 report collections and 37 job state objects in 0.000 s
2018-08-05 14:38:34: Slave Report Scan - Loading slave IDs
2018-08-05 14:38:34: Slave Report Scan - Loaded 54 slave IDs in 0.000 s
2018-08-05 14:38:34: Slave Report Scan - Purged 0 report collections, 0 job state objects, and 0 licensed Slave stubs in 0
2018-08-05 14:38:34: Slave Report Scan - Done.
2018-08-05 14:38:34: Purging Timed Out Slaves in Throttle Queue
2018-08-05 14:38:34: Purging Old Statistics
2018-08-05 14:38:34: Old Statistics - Skipping job statistics because the option to purge them is disabled in the Repository Options
2018-08-05 14:38:34: Old Statistics - Skipping slave statistics because the option to purge them is disabled in the Repository Options
2018-08-05 14:38:34: Old Statistics - Skipping repository statistics because the option to purge them is disabled in the Repository Options
2018-08-05 14:38:34: Purging expired Remote Command Tokens
2018-08-05 14:38:34: Purging Deleted Document Stubs From Database
2018-08-05 14:38:34: Deleted Document Stubs - Deleting stubs that are older than 3 days
2018-08-05 14:38:34: Deleted Document Stubs - Deleted 0 stubs in 0.000 s
2018-08-05 14:38:34: Triggering house cleaning events...
2018-08-05 14:38:34: Scheduler - Performing Job scan on Primary Pools with scheduling order Priority, Balanced
2018-08-05 14:38:34: Scheduler - Using enhanced scheduler balancing
2018-08-05 14:38:34: Scheduler - Successfully dequeued 1 task(s). Returning.
2018-08-05 14:38:34: Scheduler - Returning limit stubs not in use.
2018-08-05 14:38:34: Scheduler Thread - Skipping Z: because it is already mapped
2018-08-05 14:38:35: 0: Got task!
2018-08-05 14:38:35: 0: Executing plugin command of type 'Sync Files for Job'
2018-08-05 14:38:35: 0: All job files are already synchronized
2018-08-05 14:38:35: 0: Plugin Houdini was already synchronized.
2018-08-05 14:38:35: 0: Done executing plugin command of type 'Sync Files for Job'
2018-08-05 14:38:35: 0: Plugin already loaded: Houdini
2018-08-05 14:38:35: 0: Start Job timeout is disabled.
2018-08-05 14:38:35: 0: Task timeout is disabled.
2018-08-05 14:38:35: 0: Job already loaded: sol_ori_020_fx_fx_main_v012 - /obj/ropnet1/orion_distant
2018-08-05 14:38:35: 0: Plugin rendering frame(s): 1051-1055
2018-08-05 14:38:35: 0: Executing plugin command of type 'Render Task'
2018-08-05 14:38:35: 0: INFO: Starting Houdini Job
2018-08-05 14:38:35: 0: INFO: Stdout Redirection Enabled: True
2018-08-05 14:38:35: 0: INFO: Stdout Handling Enabled: True
2018-08-05 14:38:35: 0: INFO: Popup Handling Enabled: True
2018-08-05 14:38:35: 0: INFO: QT Popup Handling Enabled: False
2018-08-05 14:38:35: 0: INFO: WindowsForms10.Window.8.app.* Popup Handling Enabled: False
2018-08-05 14:38:35: 0: INFO: Using Process Tree: True
2018-08-05 14:38:35: 0: INFO: Hiding DOS Window: True
2018-08-05 14:38:35: 0: INFO: Creating New Console: False
2018-08-05 14:38:35: 0: INFO: Running as user: renderagent
2018-08-05 14:38:35: 0: INFO: Executable: "C:\Program Files\Side Effects Software\Houdini 16.5.473\bin\Hython.exe"
2018-08-05 14:38:35: 0: INFO: Argument: "C:\Users\renderagent\AppData\Local\Thinkbox\Deadline10\slave\gb1-04\plugins\5b64f1301405a64d9c450bdf\hrender_dl.py" -f 1051 1055 1 -g -d /obj/ropnet1/orion_distant "C:/Users/renderagent/AppData/Local/Thinkbox/Deadline10/slave/gb1-04/jobsData/5b64f1301405a64d9c450bdf/sol_ori_020_fx_fx_main_v012.hipnc"
2018-08-05 14:38:35: 0: INFO: Full Command: "C:\Program Files\Side Effects Software\Houdini 16.5.473\bin\Hython.exe" "C:\Users\renderagent\AppData\Local\Thinkbox\Deadline10\slave\gb1-04\plugins\5b64f1301405a64d9c450bdf\hrender_dl.py" -f 1051 1055 1 -g -d /obj/ropnet1/orion_distant "C:/Users/renderagent/AppData/Local/Thinkbox/Deadline10/slave/gb1-04/jobsData/5b64f1301405a64d9c450bdf/sol_ori_020_fx_fx_main_v012.hipnc"
2018-08-05 14:38:35: 0: INFO: Startup Directory: "C:\Program Files\Side Effects Software\Houdini 16.5.473\bin"
2018-08-05 14:38:35: 0: INFO: Process Priority: BelowNormal
2018-08-05 14:38:35: 0: INFO: Process Affinity: default
2018-08-05 14:38:35: 0: INFO: Process is now running
2018-08-05 14:38:37: 0: STDOUT: Detected Houdini version: (16, 5, 473)
2018-08-05 14:38:37: 0: STDOUT: ['C:\\Users\\renderagent\\AppData\\Local\\Thinkbox\\Deadline10\\slave\\gb1-04\\plugins\\5b64f1301405a64d9c450bdf\\hrender_dl.py', '-f', '1051', '1055', '1', '-g', '-d', '/obj/ropnet1/orion_distant', 'C:/Users/renderagent/AppData/Local/Thinkbox/Deadline10/slave/gb1-04/jobsData/5b64f1301405a64d9c450bdf/sol_ori_020_fx_fx_main_v012.hipnc']
2018-08-05 14:38:37: 0: STDOUT: Start: 1051
2018-08-05 14:38:37: 0: STDOUT: End: 1055
2018-08-05 14:38:37: 0: STDOUT: Increment: 1
2018-08-05 14:38:37: 0: STDOUT: Ignore Inputs: True
2018-08-05 14:38:37: 0: STDOUT: No output specified. Output will be handled by the driver
2018-08-05 14:38:37: 0: STDOUT: Driver: /obj/ropnet1/orion_distant
2018-08-05 14:38:37: 0: STDOUT: Input File: C:/Users/renderagent/AppData/Local/Thinkbox/Deadline10/slave/gb1-04/jobsData/5b64f1301405a64d9c450bdf/sol_ori_020_fx_fx_main_v012.hipnc
2018-08-05 14:40:31: 0: STDOUT: Warnings were generated during load.
2018-08-05 14:40:31: 0: STDOUT: Error loading: C:/Users/renderagent/AppData/Local/Thinkbox/Deadline10/slave/gb1-04/jobsData/5b64f1301405a64d9c450bdf/sol_ori_020_fx_fx_main_v012.hipnc
2018-08-05 14:40:31: 0: STDOUT: Warning: "/obj/ropnet1/deadline4" using incomplete asset definition (full definition not found).
2018-08-05 14:40:31: 0: STDOUT: "/obj/ropnet1/deadline1" using incomplete asset definition (full definition not found).
2018-08-05 14:40:31: 0: STDOUT: The following node types are using incomplete asset definitions:
2018-08-05 14:40:31: 0: STDOUT: Driver/deadline
2018-08-05 14:40:31: 0: STDOUT: Vop/Motion_vectors_2D
2018-08-05 14:40:31: 0: STDOUT: ROP type: ifd
2018-08-05 14:40:31: 0: STDOUT: Enabled Alfred style progress
2018-08-05 14:40:31: 0: STDOUT: Set verbosity to 3
2018-08-05 14:40:31: 0: STDOUT: Rendering frame 1051 to 1055
2018-08-05 14:40:32: 0: STDOUT: Unknown operator type: Driver/deadline
2018-08-05 14:40:32: 0: STDOUT: Unknown operator type: Shop/sol_fisheye_lens
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OOPS:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: File
2018-08-05 14:40:32: 0: STDOUT: Unknown command: return
2018-08-05 14:40:32: 0: STDOUT: Unknown command: OperationFailed:
2018-08-05 14:40:32: 0: STDOUT: Unknown command: Cannot
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/7284_sol_ori_020_fx_fx_main_v012_shared_38039.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/orion_far/OUT(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_38516.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/asteroid_field/OUT(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_38914.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_meteor/matchsize1(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_38933.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/expl_cone/merge1(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39017.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_asteroid_sim1/transform3(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39123.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_38933.bgeo.sc'
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_asteroid_sim2/transform3(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39017.bgeo.sc'
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39123.bgeo.sc'
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39176.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_asteroid_sim3/transform3(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39217.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_asteroid_sim4/transform3(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/7284_sol_ori_020_fx_fx_main_v012_shared_39283.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39217.bgeo.sc'
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_asteroid_sim5/null1(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_38914.bgeo.sc'
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39334.bgeo.sc
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] /obj/hero_asteroid_sim6/transform3(loading...)
2018-08-05 14:40:32: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39351.bgeo.sc
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39334.bgeo.sc'
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] /obj/equatorial_fine_debris/OUT(loading...)
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39447.bgeo.sc
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] /obj/frame/material1(loading...)
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39587.bgeo.sc
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] /obj/small_planet_impacts/OUT(loading...)
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39447.bgeo.sc'
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] reading geometry from C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/7284_sol_ori_020_fx_fx_main_v012_shared_39591.bgeo.sc
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] /obj/Ori_planet_ring/material1(loading...)
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39176.bgeo.sc'
2018-08-05 14:40:33: 0: STDOUT: [14:40:32] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39587.bgeo.sc'
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] Generating Image: Z:/jobs/sol/sequences/ori/ori_020/fx/fx/work/houdini/render/orion_distant/v012/f2048/jpg/orion_distant.1051.jpg (2048x2048)
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] Plane[C]: 0 Cf+Af[4] (16-bit float)
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] SampleFilter: alpha
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] PixelFilter: gaussian -w 2
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] VEX Type: vector4
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] Gamma: 1
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] Dither: 0.5
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] Gain: 1
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] White point: 1
2018-08-05 14:40:34: 0: STDOUT: [14:40:34] Will be writing to checkpoint file Z:/jobs/sol/sequences/ori/ori_020/fx/fx/work/houdini/render/orion_distant/v012/f2048/jpg/orion_distant.1051.jpg.mantra_checkpoint
2018-08-05 14:40:39: 0: STDOUT: [14:40:39] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_39351.bgeo.sc'
2018-08-05 14:40:46: 0: STDOUT: [14:40:46] Deleting temporary geometry 'C:/Users/RENDER~1/AppData/Local/Temp/houdini_temp/ifds/storage/8096_sol_ori_020_fx_fx_main_v012.1051_000_38516.bgeo.sc'
2018-08-05 14:40:46: 0: STDOUT: [14:40:46] Waited 12.08 to load geometry /obj/asteroid_field/OUT
2018-08-05 14:40:46: 0: STDOUT: [14:40:46] Load Time:
2018-08-05 14:40:46: 0: STDOUT: Frame Wall Clock Time: 0:01:48.53
2018-08-05 14:40:46: 0: STDOUT: Total Wall Clock Time: 0:01:48.53
2018-08-05 14:40:46: 0: STDOUT: Total CPU Time: 0:00:28.62
2018-08-05 14:40:46: 0: STDOUT: System CPU Time Only: 0:00:11.75
2018-08-05 14:40:46: 0: STDOUT: Peak Memory Usage: 726.58 MB
2018-08-05 14:40:46: 0: STDOUT: [14:40:46] VEX Shaders Loaded:
2018-08-05 14:40:46: 0: STDOUT: C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/mislighting.vfl C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/mislighting.vfl
2018-08-05 14:40:46: 0: STDOUT: C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/pbrlighting.vfl C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/pbrlighting.vfl
2018-08-05 14:40:46: 0: STDOUT: C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/physicalsss_multi.vfl C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/physicalsss_multi.vfl
2018-08-05 14:40:46: 0: STDOUT: C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/physicalsss_single.vfl C:/PROGRA~1/SIDEEF~1/HOUDIN~1.473/houdini/vex/Surface/physicalsss_single.vfl
2018-08-05 14:40:46: 0: STDOUT: op:/obj/asteroid_field/popnet/popsprite1/shopnet1/spritefog op:/obj/asteroid_field/popnet/popsprite1/shopnet1/spritefog
2018-08-05 14:40:46: 0: STDOUT: op:/obj/camera_frustrum_ql2/camera_frustrum/camera_frustrum_ql1/plane_far/SHOP/Camera_Plane op:/obj/camera_frustrum_ql2/camera_frustrum/camera_frustrum_ql1/plane_far/SHOP/Camera_Plane
2018-08-05 14:40:46: 0: STDOUT: op:/obj/camera_frustrum_ql2/camera_frustrum/camera_frustrum_ql1/plane_near/SHOP/Camera_Plane op:/obj/camera_frustrum_ql2/camera_frustrum/camera_frustrum_ql1/plane_near/SHOP/Camera_Plane
2018-08-05 14:40:46: 0: STDOUT: op:/obj/equatorial_fine_debris/popnet/popsprite1/shopnet1/spritefog op:/obj/equatorial_fine_debris/popnet/popsprite1/shopnet1/spritefog
2018-08-05 14:40:46: 0: STDOUT: op:/obj/hero_asteroid_instances/matnet1/principledshader1 op:/obj/hero_asteroid_instances/matnet1/principledshader1
2018-08-05 14:40:46: 0: STDOUT: op:/obj/matnet1/billowysmoke_shockwave op:/obj/matnet1/billowysmoke_shockwave
2018-08-05 14:40:46: 0: STDOUT: op:/obj/matnet1/constant op:/obj/matnet1/constant
2018-08-05 14:40:46: 0: STDOUT: op:/obj/matnet1/constant_Ori_planet_ring op:/obj/matnet1/constant_Ori_planet_ring
2018-08-05 14:40:46: 0: STDOUT: op:/obj/matnet1/principledshader_small_impacts op:/obj/matnet1/principledshader_small_impacts
2018-08-05 14:40:46: 0: STDOUT: op:/obj/sol_dome_camera_rig1/camera_frustrum_ql1/camera_frustrum/camera_frustrum_ql1/plane_far/SHOP/Camera_Plane op:/obj/sol_dome_camera_rig1/camera_frustrum_ql1/camera_frustrum/camera_frustrum_ql1/plane_far/SHOP/Camera_Plane
2018-08-05 14:40:46: 0: STDOUT: op:/obj/sol_dome_camera_rig1/camera_frustrum_ql1/camera_frustrum/camera_frustrum_ql1/plane_near/SHOP/Camera_Plane op:/obj/sol_dome_camera_rig1/camera_frustrum_ql1/camera_frustrum/camera_frustrum_ql1/plane_near/SHOP/Camera_Plane
2018-08-05 14:40:46: 0: STDOUT: op:/obj/sol_dome_camera_rig1/camera_plane_ql1/Camera_Plane/camera_plane_ql1/SHOP/Camera_Plane op:/obj/sol_dome_camera_rig1/camera_plane_ql1/Camera_Plane/camera_plane_ql1/SHOP/Camera_Plane
2018-08-05 14:40:46: 0: STDOUT: opdef:/Shop/v_arealight opdef:/Shop/v_arealight
2018-08-05 14:40:46: 0: STDOUT: opdef:/Shop/v_constant opdef:/Shop/v_constant
2018-08-05 14:40:46: 0: STDOUT: opdef:/Shop/v_layered opdef:/Shop/v_layered
2018-08-05 14:40:46: 0: STDOUT: opdef:/Vop/principledshader::2.0?SurfaceVexCode opdef:/Vop/principledshader::2.0?SurfaceVexCode
2018-08-05 14:40:46: 0: STDOUT: op:/obj/hero_asteroid_instances/matnet1/principledshader1 op:/obj/hero_asteroid_instances/matnet1/principledshader1
2018-08-05 14:40:46: 0: STDOUT: opdef:/Vop/principledshader::2.0?DisplacementVexCode opdef:/Vop/principledshader::2.0?DisplacementVexCode
2018-08-05 14:40:46: 0: STDOUT: opdef:/Shop/v_asadlight opdef:/Shop/v_asadlight
2018-08-05 14:40:46: 0: STDOUT: opdef:/Shop/v_rayshadow opdef:/Shop/v_rayshadow
2018-08-05 14:40:46: 0: STDOUT: [14:40:46]
2018-08-05 14:40:46: 0: STDOUT: [14:40:46] Thread Count: 48
2018-08-05 14:40:52: 0: STDOUT: [14:40:52] Converting texture 'Z:/jobs/sol/sequences/ori/ori_020/fx/fx/work/houdini/tex/sol_starfieldOri_dsgn_design_proplydSky_v003.jpg' to .rat format. This uses approximately 682.67 MB of extra memory.
2018-08-05 14:41:03: 0: STDOUT: [14:41:03] mantra: [RAY_ProcGT] procedural warning: Optimization not run on geometry (this may be ok)
2018-08-05 14:43:31: Skipping thermal shutdown check because it is not required at this time
2018-08-05 14:53:57: Skipping thermal shutdown check because it is not required at this time
2018-08-05 15:04:27: Skipping thermal shutdown check because it is not required at this time
2018-08-05 15:14:55: Skipping thermal shutdown check because it is not required at this time
2018-08-05 15:25:32: Skipping thermal shutdown check because it is not required at this time
2018-08-05 15:36:09: Skipping thermal shutdown check because it is not required at this time
2018-08-05 15:46:37: Power Management - Thermal Shutdown: Skipping zone "blades" because it is disabled
2018-08-05 15:57:08: Skipping thermal shutdown check because it is not required at this time
2018-08-05 16:07:40: Power Management - Thermal Shutdown: Skipping zone "blades" because it is disabled
2018-08-05 16:18:07: Power Management - Thermal Shutdown: Skipping zone "blades" because it is disabled
2018-08-05 16:28:35: Skipping thermal shutdown check because it is not required at this time
2018-08-05 16:39:02: Skipping thermal shutdown check because it is not required at this time
2018-08-05 16:49:35: Skipping thermal shutdown check because it is not required at this time
2018-08-05 17:00:36: Skipping thermal shutdown check because it is not required at this time
2018-08-05 17:11:04: Skipping thermal shutdown check because it is not required at this time
2018-08-05 17:21:32: Skipping thermal shutdown check because it is not required at this time
2018-08-05 17:32:06: Skipping thermal shutdown check because it is not required at this time
2018-08-05 17:42:45: Skipping thermal shutdown check because it is not required at this time
2018-08-05 18:22:01: Listener Thread - ::ffff:192.168.1.7 has connected
2018-08-05 18:22:01: Listener Thread - Received message: StreamLog
2018-08-05 18:26:10: Listener Thread - ::ffff:192.168.1.7 has connected
Success