AWS Thinkbox Discussion Forums

Houdini 16.5.473 would stall on Skipping thermal shutdown check because it is not required at this time

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

Hmm… Taking a look at this. The part that stands out to me is this:

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
...
(repeats)
...

At this point we’ve called the render function on the mantra ROP. So this is all coming from Houdini/Mantra. My guess here is that it failed because of whatever caused those messages and stopped the render, but didn’t exit the process further on. We also did not kill the process because we’re unaware of this being a fail state, so Houdini just stays open and we’re just like “yes, this task is still rendering”.

The “Unknown operator type” for Driver/deadline should be fine, since we’re not actually doing anything with the node anymore. I’m not sure how of the impact of having warnings/load errors on “Shop/sol_fisheye_lens” and “Vop/Motion_vectors_2D” affects your particular render.

Few questions here. What’s the full Deadline10 version you’re using? Are the two logs here for the same job/same ROP? I can’t see exact confirmation that that’s the case. If so, could you attach the full logs for a correctly completed task for this ROP? Is this easily reproducible in a test scene, does it only happen in that one scene for that one specific ROP?

Cheers

Privacy | Site terms | Cookie preferences