Render to disk very slow

[WoutTgh]

Hi,

I've attached a scene file to show something weird going on when rendering to disk / Mplay on Houdini 18.5. The scene renders almost instantly in IPR but when rendering to disk or MPlay, it takes about 5 seconds per frame.

Thank you !

Wout

[WoutTgh]

Here's a log file. It's weird, it always takes 10 seconds inbetween:

15:20:10 INFOR: [render] ------------ Render setup, set renderTarget and update
15:20:20 INFOR: [render stats] ------ Objects: 2, Triangles: 540, Hairs: 0, Voxels: 0, Particles: 0




Log started Sat Oct 31 15:20:10 2020
Octane Render for Houdini by Juanjo Gonzalez. Build 2020.1.5.2 (Build date: Oct 23 2020 19:45:51)
Octane API: 2020.1.5
HDK API: 18.5.351
Houdini host version: 18.5.351
System: GenuineIntel unknown, 3402 Mhz., 4 cores
OS: Windows 10 64-bit
*** Studio subscription license ***

15:20:10 INFOR: [license] ----------- This Octane License is already active
15:20:10 INFOR: [system] ------------ Setting the GPUs device state and priority
15:20:10 INFOR: [system] ------------ >>> Device 0: state 1, priority 1, denoise 1, tonemap 1
15:20:10 INFOR: [system] ------------ >>> Device 1: state 1, priority 1, denoise 1, tonemap 1
15:20:10 INFOR: [core] -------------- ROP node render, time 0.46
15:20:10 INFOR: [core] -------------- Render layers disabled
15:20:10 INFOR: [loader] ------------ Houdini camera path: /obj/cam1
15:20:10 INFOR: [loader] ------------ Loading scene
15:20:10 INFOR: [loader] ------------ Building bunde lists
15:20:10 INFOR: [loader] ------------ Loading objects. First pass
15:20:10 INFOR: [loader] ------------ Found a OBJ node: /obj/box_object1
15:20:10 INFOR: [loader] ------------ Render SOP for this node: /obj/box_object1/box1, name box1
15:20:10 INFOR: [loader] ------------ Found a OBJ node: /obj/cam1
15:20:10 INFOR: [loader] ------------ Found a OBJ node: /obj/sphere_object1
15:20:10 INFOR: [loader] ------------ Render SOP for this node: /obj/sphere_object1/sphere1, name sphere1
15:20:10 INFOR: [materials] --------- Building the materials list
15:20:10 INFOR: [materials] --------- Found 1 materials
15:20:10 INFOR: [materials] --------- Building the materials list
15:20:10 INFOR: [materials] --------- Found 1 materials
15:20:10 INFOR: [loader] ------------ Transformation matrix for MB step 0
15:20:10 INFOR: [loader] ------------ Transformation matrix for MB step 0
15:20:10 INFOR: [loader] ------------ Loading objects. Second pass
15:20:10 INFOR: [loader] ------------ Building Octane objects nodes tree
15:20:10 INFOR: [loader] ------------ Cleaning bunde lists
15:20:10 INFOR: [loader] ------------ Building the render target
15:20:10 ERROR: [rt vop] ------------ The Octane node has not been found in the linked node
15:20:10 INFOR: [loader] ------------ Loading the Houdini camera parameters
15:20:10 INFOR: [loader] ------------ Houdini camera path: /obj/cam1
15:20:10 INFOR: [loader] ------------ Houdini camera path: /obj/cam1
15:20:10 INFOR: [loader] ------------ Scene Loaded
15:20:10 INFOR: [loader] ------------ Setting the motion blur parameters
15:20:10 INFOR: [loader] ------------ Houdini camera path: /obj/cam1
15:20:10 INFOR: [render] ------------ Render setup, set renderTarget and update
15:20:20 INFOR: [render stats] ------ Objects: 2, Triangles: 540, Hairs: 0, Voxels: 0, Particles: 0
15:20:20 INFOR: [render stats] ------ Textures (8/16/32/64): 0 / 0 / 0 / 0
15:20:20 INFOR: [render stats] ------ GPU RAM (used/free/total): 587 / 8390/ 11264 [MB]
15:20:20 INFOR: [render stats] ------ Sys RAM (used/free): 8031 / 16256, Total mem. used: 1071 [MB]
15:20:20 INFOR: [render stats] ------ Out-of-core RAM (used/max) 0 / 4096 [MB]
15:20:20 INFOR: [render stats] ------ Local GPUs: 2, Net GPUs: 0, Net memory: 0 [MB]
15:20:20 INFOR: [save image] -------- Save image function enabled
15:20:20 INFOR: [save image] -------- Saving the "Beauty" pass to the "D:/PROJECTS/HOTEL_HUNGARIA_UMICORE/02_PROJECTS/01_HOUDINI/05_RENDER/QUEUE_TEST/test_0012" file
15:20:20 INFOR: [render] ------------ - Cleaning the scene. Full clean state 0

End of the log system

[WoutTgh]

From a log file with debugging enabled:

this seems to be where the slow down is :


15:45:15 DEBUG: [profile] ----------- Function "HOctane_renderScene. Octane scene preprocessing time." over "(null)" execution time: 10.069 seconds



10 seconds preprocessing ( scene is one cube)

[juanjgon]

This is a known issue that will be fixed in Octane 2020.2. Check this post about it:
viewtopic.php?f=101&t=76126

Thanks,
-Juanjo

[WoutTgh]

Right, so it's a 10-seconds-built-in delay. When will the new version be out ?

[juanjgon]

Right, so it's a 10-seconds-built-in delay. When will the new version be out ?

We are working on the new Octane 2020.2 builds as we speak, but I'm thinking about releasing a new Octane 2020.1 plugin build including an option to disable this "feature" before, later this week.

Thanks,
-Juanjo

[WoutTgh]

Oh that would be lovely ! I'm in the middle of a project and quick preview renders would be very helpful. Thank you !

[juanjgon]

The new build is ready:
viewtopic.php?f=102&t=76065

Thanks,
-Juanjo

[WoutTgh]

Great, works like a charm ! Thank you !