00:00:00 (0000.00) | Log started Fri Nov 10 06:48:54 2017
00:00:00 (0000.00) | Octane Render for Lightwave by Juanjo González. Build 3.07.0.1 (Build date: Nov  4 2017 00:49:00)
00:00:00 (0000.08) | Octane API: 3.07
00:00:00 (0000.08) | System: GenuineIntel Intel(R) Xeon(R) CPU E31220 @ 3.10GHz, 3085 Mhz., 4 cores
00:00:00 (0000.08) | OS: Windows 8 64-bit
00:00:00 (0000.08) | 
00:00:00 (0000.08) | Init rendering from frame 0 to 0 step 1
00:00:00 (0000.08) |  Range rendering disabled for F9 still images
00:00:00 (0000.08) | Init preview window
00:00:00 (0000.09) |         >>> Draw preview window progress bar
00:00:00 (0000.09) | Enter inside the main rendering function
00:00:00 (0000.09) | Render layers disabled
00:00:00 (0000.09) | 
00:00:00 (0000.09) | ()() RENDERING FRAME 0
00:00:00 (0000.09) | ()()() Evaluating scene for frame 0
00:00:00 (0000.13) | 
00:00:00 (0000.13) | Init scene
00:00:00 (0000.13) |       >>> Refresh preview window
00:00:00 (0000.13) |         >>> Draw preview window progress bar
00:00:00 (0000.13) |       >>> Refresh preview window done
00:00:00 (0000.13) | Init the root geometryGroup
00:00:00 (0000.13) | 
00:00:00 (0000.13) | Processing the motion blur data
00:00:00 (0000.13) |   Get the scene motion blur settings
00:00:00 (0000.13) |     Motion blur disabled, computing only one time sample
00:00:00 (0000.13) |   Processing time sample 1 of 1, time: 0.000000
00:00:00 (0000.13) |     Get camera motion blur data
00:00:00 (0000.13) |      * position: 1.058455 2.835000 11.575439
00:00:00 (0000.13) |      * target: -5.172449 -9.525148 -87.461940
00:00:00 (0000.13) |      * fov: 45.239731
00:00:00 (0000.13) |     Get Objects motion blur data
00:00:00 (0000.13) |       Object five_hairs:base transformation matrix:
00:00:00 (0000.13) |        1.000000 0.000000 -0.000000 0.000000
00:00:00 (0000.13) |        0.000000 1.000000 0.000000 0.000000
00:00:00 (0000.13) |        -0.000000 -0.000000 1.000000 -0.000000
00:00:00 (0000.13) |       Object five_hairs:hairs transformation matrix:
00:00:00 (0000.13) |        1.000000 0.000000 -0.000000 0.000000
00:00:00 (0000.13) |        0.000000 1.000000 0.000000 0.000000
00:00:00 (0000.13) |        -0.000000 -0.000000 1.000000 -0.000000
00:00:00 (0000.13) |     Get vertex deformations motion blur data
00:00:00 (0000.13) |       Object five_hairs:hairs has deformations, reading the vertex motion data
00:00:00 (0000.13) |         >>> Processing vertex list
00:00:00 (0000.13) | [profile] Function "LWOctane_addVertex" over "" execution time: 0.000 seconds
00:00:00 (0000.13) |     Get Lights motion blur data
00:00:00 (0000.13) |       Light Light transformation matrix:
00:00:00 (0000.13) |        0.707107 0.405580 -0.579228 -2.000000
00:00:00 (0000.13) |        0.000000 0.819152 0.573576 2.000000
00:00:00 (0000.13) |        0.707107 -0.405580 0.579228 2.000000
00:00:00 (0000.13) | Motion blur data ready
00:00:00 (0000.13) | 
00:00:00 (0000.13) | Processing objects list from LW scene. FIRST PASS
00:00:00 (0000.13) |   Number of LW objects: 2
00:00:00 (0000.13) | 
00:00:00 (0000.13) |   -> Reading scene UV Maps list
00:00:00 (0000.13) |   --> Detected 0 UVMaps in scene
00:00:00 (0000.13) | 
00:00:00 (0000.13) | Processing object: 10000000, name: five_hairs:base 
00:00:00 (0000.13) |   Object five_hairs:base is a LW object without clones, we must add the mesh to Octane database
00:00:00 (0000.13) |   Searching if FiberFX is available in this object
00:00:00 (0000.13) |         >>> Processing the number of valid polygons
00:00:00 (0000.13) |         >>> This object has 6 of 6 valid polygons
00:00:00 (0000.13) |       >>> Refresh preview window
00:00:00 (0000.13) |         >>> Draw preview window progress bar
00:00:00 (0000.13) |       >>> Refresh preview window done
00:00:00 (0000.13) |       >>> Adding a new mesh to Octane
00:00:00 (0000.13) |       >>> Mesh points: 8, valid polygons: 6
00:00:00 (0000.13) |         ----> Searching UV maps in this object
00:00:00 (0000.13) |         ----> No UV maps found in this object
00:00:00 (0000.13) | [profile] Function "LWOctane_getVMap" over "" execution time: 0.000 seconds
00:00:00 (0000.13) |         >>> Processing vertex per poly array
00:00:00 (0000.13) | [profile] Function "LWOctane_getVertsPerPoly" over "" execution time: 0.000 seconds
00:00:00 (0000.13) |         >>> Processing vertex list
00:00:00 (0000.13) | [profile] Function "LWOctane_addVertex" over "" execution time: 0.000 seconds
00:00:00 (0000.13) |       +++ Searching object materials
00:00:00 (0000.13) |       +++ Found 3 materials inside five_hairs:base
00:00:00 (0000.13) |         +++ Material 0 ID: Default
00:00:00 (0000.13) |         +++ Material 1 ID: hair_mat1
00:00:00 (0000.13) |         +++ Material 2 ID: hair_mat2
00:00:00 (0000.13) |       +++ Searching in the object materials for UVMAP nodes
00:00:00 (0000.13) |         >>> Searching UVMAP node inside surface Default
00:00:00 (0000.13) |           >>> This material doesn't have a valid UVMAP node
00:00:00 (0000.13) |         >>> Searching UVMAP node inside surface hair_mat1
00:00:00 (0000.13) |           >>> This material doesn't have a valid UVMAP node
00:00:00 (0000.13) |         >>> Searching UVMAP node inside surface hair_mat2
00:00:00 (0000.13) |           >>> This material doesn't have a valid UVMAP node
00:00:00 (0000.13) |         >>> Processing polygon data
00:00:00 (0000.13) | [profile] Function "LWOctane_addPolData" over "" execution time: 0.000 seconds
00:00:00 (0000.13) |       >>> Mesh added, evaluating it
00:00:00 (0000.13) | [profile] Function "LWOctane_addMesh" over "" execution time: 0.000 seconds
00:00:00 (0000.13) |       >>> Refresh preview window
00:00:00 (0000.13) |         >>> Draw preview window progress bar
00:00:00 (0000.13) |       >>> Refresh preview window done
00:00:00 (0000.13) |       +++ Processing object materials
00:00:00 (0000.13) |         > Filtering 3 materials in 6 polygons
00:00:00 (0000.13) |         > Filtering 3 materials in 0 hairs
00:00:00 (0000.13) |           Used materials in this object: 1
00:00:00 (0000.13) |         +++ Processing material Default
00:00:00 (0000.13) |           +++ This material only has a basic parameters, not active node editor
00:00:00 (0000.13) |           +++ Basic parameters added
00:00:00 (0000.13) |       +++ End of materials processing
00:00:00 (0000.13) |   Searching FiberFX hair materials
00:00:00 (0000.13) |     Found FiberFX data in this object, processing materials
00:00:00 (0000.13) |     Hair materials loaded in the Octane scene
00:00:00 (0000.13) |       +++ Processing object layer maps functions
00:00:00 (0000.14) |           --> Get object global dissolve value: 0.000000, state 0
00:00:00 (0000.14) | [profile] Function "LWOctane_addGeom" over "five_hairs:base" execution time: 0.001 seconds
00:00:00 (0000.14) | 
00:00:00 (0000.14) | Processing object: 10000001, name: five_hairs:hairs 
00:00:00 (0000.14) |   Object five_hairs:hairs is a LW object without clones, we must add the mesh to Octane database
00:00:00 (0000.14) |   Searching if FiberFX is available in this object
00:00:00 (0000.14) |   This object has deformations
00:00:00 (0000.14) |         >>> Processing the number of valid polygons
00:00:00 (0000.14) |         >>> This object has 0 of 14 valid polygons
00:00:00 (0000.14) |       >>> Refresh preview window
00:00:00 (0000.14) |         >>> Draw preview window progress bar
00:00:00 (0000.14) |       >>> Refresh preview window done
00:00:00 (0000.14) |   Searching FiberFX hair data in the object
00:00:00 (0000.14) |     Hair data found. Number of systems: 1
00:00:00 (0000.14) |     Octane mesh is empty, we need to build a new mesh
00:00:00 (0000.14) |       +++ Searching object materials
00:00:00 (0000.14) |       +++ Found 3 materials inside five_hairs:hairs
00:00:00 (0000.14) |         +++ Material 0 ID: Default
00:00:00 (0000.14) |         +++ Material 1 ID: hair_mat1
00:00:00 (0000.14) |         +++ Material 2 ID: hair_mat2
00:00:00 (0000.14) |         ----> Searching UV maps in this object
00:00:00 (0000.14) |         ----> No UV maps found in this object
00:00:00 (0000.14) | [profile] Function "LWOctane_getVMap" over "" execution time: 0.000 seconds
00:00:00 (0000.14) |       Processing system 0, id 216842272: 625 fibers, 7625 vertex
00:00:00 (0000.14) |     Hair data processed. Total fibers: 625, total vertex: 7625
00:00:00 (0000.14) |     Hair data loaded in the Octane scene
00:00:00 (0000.14) |       >>> Refresh preview window
00:00:00 (0000.14) |         >>> Draw preview window progress bar
00:00:00 (0000.14) |       >>> Refresh preview window done
00:00:00 (0000.14) |       +++ Processing object materials
00:00:00 (0000.14) |         > Filtering 4 materials in 0 polygons
00:00:00 (0000.14) |         > Filtering 4 materials in 625 hairs
00:00:00 (0000.14) |           Used materials in this object: 1
00:00:00 (0000.14) |       +++ End of materials processing
00:00:00 (0000.14) |   Searching FiberFX hair materials
00:00:00 (0000.14) |     Found FiberFX data in this object, processing materials
00:00:00 (0000.14) |     Loading material for system 0
00:00:01 (0001.40) |     Hair materials loaded in the Octane scene
00:00:01 (0001.40) |       +++ Processing object layer maps functions
00:00:01 (0001.40) |           --> Get object global dissolve value: 0.000000, state 0
00:00:01 (0001.40) | [profile] Function "LWOctane_addGeom" over "five_hairs:hairs" execution time: 1.263 seconds
00:00:01 (0001.40) |       >>> Refresh preview window
00:00:01 (0001.40) |         >>> Draw preview window progress bar
00:00:01 (0001.40) |       >>> Refresh preview window done
00:00:01 (0001.40) | 
00:00:01 (0001.40) | Processing objects list from LW scene. SECOND PASS
00:00:01 (0001.40) |   Second pass check for object 10000000
00:00:01 (0001.40) |      Object five_hairs:base has the native Lightwave instancer plugin with 0 instances
00:00:01 (0001.40) |        Processing instances ...
00:00:01 (0001.40) |   Second pass check for object 10000000 ok.
00:00:01 (0001.40) |   Second pass check for object 10000001
00:00:01 (0001.40) |      Object five_hairs:hairs has the native Lightwave instancer plugin with 0 instances
00:00:01 (0001.40) |        Processing instances ...
00:00:01 (0001.40) |   Second pass check for object 10000001 ok.
00:00:01 (0001.40) | Setting the objects geometry group to the scene root geometry group, index 0
00:00:01 (0001.40) | Setting the instances geometry group to the scene root geometry group, index 2
00:00:01 (0001.40) |   -> Releasing scene UV Maps list
00:00:01 (0001.40) | Objects processing end ok
00:00:01 (0001.40) | 
00:00:01 (0001.40) | 
00:00:01 (0001.40) | Processing Octane lights from LW scene.
00:00:01 (0001.40) |       >>> Refresh preview window
00:00:01 (0001.40) |         >>> Draw preview window progress bar
00:00:01 (0001.40) |       >>> Refresh preview window done
00:00:01 (0001.40) |   Number of LW lighs in scene: 1
00:00:01 (0001.40) | 
00:00:01 (0001.40) |   Reading light info - name: Light
00:00:01 (0001.40) |     Light Light, type distant, not supported by Octante currently
00:00:01 (0001.40) | 
00:00:01 (0001.40) | Setting the lights geometry group to the scene root geometry group, index 1
00:00:01 (0001.40) | Lights processing end ok
00:00:01 (0001.40) | 
00:00:01 (0001.40) | Init the renderTarget
00:00:01 (0001.40) | Setting the root geometryGroup to the renderTarget
00:00:01 (0001.40) | Setting the default render target nodes
00:00:01 (0001.40) | Evaluating render target nodal editor
00:00:01 (0001.40) |  + Processing the ThinLensCamera node
00:00:01 (0001.40) |  + Processing the DayLightEnvironment node
00:00:01 (0001.40) |  + Processing the Imager node
00:00:01 (0001.40) |  + Processing the PathTracing node
00:00:01 (0001.40) |  + Processing the PortProcessing node
00:00:01 (0001.40) |  + Processing the RenderPasses node
00:00:01 (0001.40) | Render target nodal editor evaluated succesfully
00:00:01 (0001.40) | Get or update scene camera
00:00:01 (0001.40) | Camera type is THINLENS_CAMERA
00:00:01 (0001.40) |   Removing the old camera motion blur data
00:00:01 (0001.40) |   Removing the old objects motion blur data
00:00:01 (0001.40) |   Removing the old objects deformations motion blur data
00:00:01 (0001.40) |   Removing the old lights motion blur data
00:00:01 (0001.40) | 
00:00:01 (0001.40) | Scene loaded
00:00:01 (0001.40) | Render resolution: 800 x 600 
00:00:01 (0001.40) | 
00:00:01 (0001.40) | ()()() RENDERING ...
00:00:01 (0001.40) | ... set image callback ...
00:00:01 (0001.40) | 
00:00:01 (0001.40) | Render scene, set renderTarget and update
00:00:01 (0001.40) |       >>> Refresh preview window
00:00:01 (0001.40) |         >>> Draw preview window progress bar
00:00:01 (0001.40) |       >>> Refresh preview window done
00:00:01 (0001.40) |   ... first update
00:00:01 (0001.40) |   ... setRenderPriority
00:00:01 (0001.40) |   ... RenderPriority set to 0
00:00:01 (0001.40) |   ... set Motion Blur parameters
00:00:01 (0001.40) |   ... setRenderTargetNode
00:00:01 (0001.59) |   ... second update
00:00:01 (0001.59) |   ... isRenderingPaused
00:00:01 (0001.59) |       >>> Refresh preview window
00:00:01 (0001.59) |         >>> Draw preview window progress bar
00:00:01 (0001.59) |       >>> Refresh preview window done
00:00:01 (0001.59) | Render setup Ok.
00:00:01 (0001.59) | 
00:00:01 (0001.59) | [profile] Function "LWOctane_renderScene" over "" execution time: 0.187 seconds
00:00:01 (0001.59) |  <> Mem Used/Free/Total: 0 / 0 / 0
00:00:01 (0001.59) |  <> Triangles/DisplaceTris/Hairs/Objects/Voxels rendered: 12 / 0 / 7000 / 2 / 0
00:00:01 (0001.59) |  <> Textures RGB32/RGB64/GREY8/GREY16 used: 0 / 0 / 0 / 0
00:00:01 (0001.59) | 
00:00:01 (0001.59) | ... Init rendering ...
00:00:01 (0001.84) |       >>> processing image callback
00:00:01 (0001.84) |     Reading preview image
00:00:01 (0001.84) |     ... Get sampling information
00:00:01 (0001.84) |     ... Statistics: 1 of 2000 samples, 0 sec, 2.99 Ms/sec
00:00:01 (0001.84) |     ... Get buffers resolution
00:00:01 (0001.84) |     ... Resolution: 800 600
00:00:01 (0001.84) |     ... Check compositing
00:00:01 (0001.84) |     ... Compositing 0
00:00:01 (0001.84) |     ... Reading frame buffer
00:00:02 (0002.23) |     Reading preview image successfully
00:00:02 (0002.23) | [profile] Function "GetPreviewImage" over "" execution time: 0.395 seconds
00:00:02 (0002.23) |       >>> Refresh preview window
00:00:02 (0002.23) |       >>> Refresh preview window done
00:00:02 (0002.23) |       >>> processing image callback
00:00:02 (0002.23) |     Reading preview image
00:00:02 (0002.23) |     ... Get sampling information
00:00:02 (0002.23) |     ... Statistics: 8 of 2000 samples, 0 sec, 7.94 Ms/sec
00:00:02 (0002.23) |     ... Get buffers resolution
00:00:02 (0002.23) |     ... Resolution: 800 600
00:00:02 (0002.23) |     ... Check compositing
00:00:02 (0002.23) |     ... Compositing 0
00:00:02 (0002.23) |     ... Reading frame buffer
00:00:02 (0002.24) |         >>> Draw preview window information
00:00:02 (0002.24) |         >>> Draw preview window status
00:00:02 (0002.24) |         >>> Draw preview window progress bar
00:00:02 (0002.24) |         >>> Draw preview image
00:00:02 (0002.24) |         >>> Draw preview image finished. Releaseing mutex
00:00:02 (0002.62) |     Reading preview image successfully
00:00:02 (0002.62) | [profile] Function "GetPreviewImage" over "" execution time: 0.381 seconds
00:00:02 (0002.62) |       >>> Refresh preview window
00:00:02 (0002.62) |       >>> Refresh preview window done
00:00:02 (0002.62) |       >>> processing image callback
00:00:02 (0002.62) |     Reading preview image
00:00:02 (0002.62) |     ... Get sampling information
00:00:02 (0002.62) |     ... Statistics: 16 of 2000 samples, 0 sec, 10.87 Ms/sec
00:00:02 (0002.62) |     ... Get buffers resolution
00:00:02 (0002.62) |     ... Resolution: 800 600
00:00:02 (0002.62) |     ... Check compositing
00:00:02 (0002.62) |     ... Compositing 0
00:00:02 (0002.62) |     ... Reading frame buffer
00:00:02 (0002.62) |         >>> Draw preview window information
00:00:02 (0002.62) |         >>> Draw preview window status
00:00:02 (0002.62) |         >>> Draw preview window progress bar
00:00:02 (0002.63) |         >>> Draw preview image
00:00:02 (0002.63) |         >>> Draw preview image finished. Releaseing mutex
00:00:03 (0003.00) |     Reading preview image successfully
00:00:03 (0003.00) | [profile] Function "GetPreviewImage" over "" execution time: 0.386 seconds
00:00:03 (0003.00) |       >>> Refresh preview window
00:00:03 (0003.00) |       >>> Refresh preview window done
00:00:03 (0003.00) |       >>> processing image callback
00:00:03 (0003.00) |     Reading preview image
00:00:03 (0003.00) |     ... Get sampling information
00:00:03 (0003.00) |     ... Statistics: 32 of 2000 samples, 1 sec, 14.35 Ms/sec
00:00:03 (0003.00) |     ... Get buffers resolution
00:00:03 (0003.00) |     ... Resolution: 800 600
00:00:03 (0003.00) |     ... Check compositing
00:00:03 (0003.00) |     ... Compositing 0
00:00:03 (0003.00) |     ... Reading frame buffer
00:00:03 (0003.00) |         >>> Draw preview window information
00:00:03 (0003.00) |         >>> Draw preview window status
00:00:03 (0003.00) |         >>> Draw preview window progress bar
00:00:03 (0003.01) |         >>> Draw preview image
00:00:03 (0003.01) |         >>> Draw preview image finished. Releaseing mutex
00:00:03 (0003.38) |     Reading preview image successfully
00:00:03 (0003.38) | [profile] Function "GetPreviewImage" over "" execution time: 0.376 seconds
00:00:03 (0003.38) |       >>> Refresh preview window
00:00:03 (0003.38) |       >>> Refresh preview window done
00:00:03 (0003.38) |         >>> Draw preview window information
00:00:03 (0003.38) |         >>> Draw preview window status
00:00:03 (0003.38) |         >>> Draw preview window progress bar
00:00:03 (0003.38) |         >>> Draw preview image
00:00:03 (0003.38) |         >>> Draw preview image finished. Releaseing mutex
00:00:04 (0004.03) |       >>> processing image callback
00:00:04 (0004.03) |     Reading preview image
00:00:04 (0004.03) |     ... Get sampling information
00:00:04 (0004.03) |     ... Statistics: 64 of 2000 samples, 2 sec, 716.93 Ms/sec
00:00:04 (0004.03) |     ... Get buffers resolution
00:00:04 (0004.03) |     ... Resolution: 800 600
00:00:04 (0004.03) |     ... Check compositing
00:00:04 (0004.03) |     ... Compositing 0
00:00:04 (0004.03) |     ... Reading frame buffer
00:00:04 (0004.42) |     Reading preview image successfully
00:00:04 (0004.42) | [profile] Function "GetPreviewImage" over "" execution time: 0.393 seconds
00:00:04 (0004.42) |       >>> Refresh preview window
00:00:04 (0004.42) |       >>> Refresh preview window done
00:00:04 (0004.43) |         >>> Draw preview window information
00:00:04 (0004.43) |         >>> Draw preview window status
00:00:04 (0004.43) |         >>> Draw preview window progress bar
00:00:04 (0004.44) |         >>> Draw preview image
00:00:04 (0004.44) |         >>> Draw preview image finished. Releaseing mutex
00:00:04 (0004.92) |       >>> processing image callback
00:00:04 (0004.92) |     Reading preview image
00:00:04 (0004.92) |     ... Get sampling information
00:00:04 (0004.92) |     ... Statistics: 128 of 2000 samples, 3 sec, 33.26 Ms/sec
00:00:04 (0004.92) |     ... Get buffers resolution
00:00:04 (0004.92) |     ... Resolution: 800 600
00:00:04 (0004.92) |     ... Check compositing
00:00:04 (0004.92) |     ... Compositing 0
00:00:04 (0004.92) |     ... Reading frame buffer
00:00:05 (0005.31) |     Reading preview image successfully
00:00:05 (0005.31) | [profile] Function "GetPreviewImage" over "" execution time: 0.395 seconds
00:00:05 (0005.31) |       >>> Refresh preview window
00:00:05 (0005.31) |       >>> Refresh preview window done
00:00:05 (0005.32) |         >>> Draw preview window information
00:00:05 (0005.32) |         >>> Draw preview window status
00:00:05 (0005.32) |         >>> Draw preview window progress bar
00:00:05 (0005.32) |         >>> Draw preview image
00:00:05 (0005.32) |         >>> Draw preview image finished. Releaseing mutex
00:00:06 (0006.66) |       >>> processing image callback
00:00:06 (0006.66) |     Reading preview image
00:00:06 (0006.66) |     ... Get sampling information
00:00:06 (0006.66) |     ... Statistics: 240 of 2000 samples, 4 sec, 34.74 Ms/sec
00:00:06 (0006.66) |     ... Get buffers resolution
00:00:06 (0006.66) |     ... Resolution: 800 600
00:00:06 (0006.66) |     ... Check compositing
00:00:06 (0006.66) |     ... Compositing 0
00:00:06 (0006.66) |     ... Reading frame buffer
00:00:07 (0007.05) |     Reading preview image successfully
00:00:07 (0007.05) | [profile] Function "GetPreviewImage" over "" execution time: 0.392 seconds
00:00:07 (0007.05) |       >>> Refresh preview window
00:00:07 (0007.05) |       >>> Refresh preview window done
00:00:07 (0007.06) |         >>> Draw preview window information
00:00:07 (0007.06) |         >>> Draw preview window status
00:00:07 (0007.06) |         >>> Draw preview window progress bar
00:00:07 (0007.06) |         >>> Draw preview image
00:00:07 (0007.06) |         >>> Draw preview image finished. Releaseing mutex
00:00:08 (0008.56) |       >>> processing image callback
00:00:08 (0008.56) |     Reading preview image
00:00:08 (0008.56) |     ... Get sampling information
00:00:08 (0008.56) |     ... Statistics: 368 of 2000 samples, 6 sec, 33.70 Ms/sec
00:00:08 (0008.56) |     ... Get buffers resolution
00:00:08 (0008.56) |     ... Resolution: 800 600
00:00:08 (0008.56) |     ... Check compositing
00:00:08 (0008.56) |     ... Compositing 0
00:00:08 (0008.56) |     ... Reading frame buffer
00:00:09 (0009.15) |     Reading preview image successfully
00:00:09 (0009.15) | [profile] Function "GetPreviewImage" over "" execution time: 0.584 seconds
00:00:09 (0009.15) |       >>> Refresh preview window
00:00:09 (0009.15) |       >>> Refresh preview window done
00:00:09 (0009.16) |         >>> Draw preview window information
00:00:09 (0009.17) |         >>> Draw preview window status
00:00:09 (0009.17) |         >>> Draw preview window progress bar
00:00:09 (0009.17) |         >>> Draw preview image
00:00:09 (0009.17) |         >>> Draw preview image finished. Releaseing mutex
00:00:10 (0010.89) |       >>> processing image callback
00:00:10 (0010.89) |     Reading preview image
00:00:10 (0010.89) |     ... Get sampling information
00:00:10 (0010.89) |     ... Statistics: 528 of 2000 samples, 9 sec, 32.29 Ms/sec
00:00:10 (0010.89) |     ... Get buffers resolution
00:00:10 (0010.89) |     ... Resolution: 800 600
00:00:10 (0010.89) |     ... Check compositing
00:00:10 (0010.89) |     ... Compositing 0
00:00:10 (0010.89) |     ... Reading frame buffer
00:00:11 (0011.41) |     Reading preview image successfully
00:00:11 (0011.41) | [profile] Function "GetPreviewImage" over "" execution time: 0.512 seconds
00:00:11 (0011.41) |       >>> Refresh preview window
00:00:11 (0011.41) |       >>> Refresh preview window done
00:00:11 (0011.43) |         >>> Draw preview window information
00:00:11 (0011.43) |         >>> Draw preview window status
00:00:11 (0011.43) |         >>> Draw preview window progress bar
00:00:11 (0011.43) |         >>> Draw preview image
00:00:11 (0011.43) |         >>> Draw preview image finished. Releaseing mutex
00:00:14 (0014.05) |       >>> processing image callback
00:00:14 (0014.05) |     Reading preview image
00:00:14 (0014.05) |     ... Get sampling information
00:00:14 (0014.05) |     ... Statistics: 896 of 2000 samples, 12 sec, 41.27 Ms/sec
00:00:14 (0014.05) |     ... Get buffers resolution
00:00:14 (0014.05) |     ... Resolution: 800 600
00:00:14 (0014.05) |     ... Check compositing
00:00:14 (0014.05) |     ... Compositing 0
00:00:14 (0014.05) |     ... Reading frame buffer
00:00:14 (0014.48) |     Reading preview image successfully
00:00:14 (0014.48) | [profile] Function "GetPreviewImage" over "" execution time: 0.434 seconds
00:00:14 (0014.48) |       >>> Refresh preview window
00:00:14 (0014.48) |       >>> Refresh preview window done
00:00:14 (0014.49) |         >>> Draw preview window information
00:00:14 (0014.49) |         >>> Draw preview window status
00:00:14 (0014.49) |         >>> Draw preview window progress bar
00:00:14 (0014.49) |         >>> Draw preview image
00:00:14 (0014.49) |         >>> Draw preview image finished. Releaseing mutex
00:00:18 (0018.14) |       >>> processing image callback
00:00:18 (0018.14) |     Reading preview image
00:00:18 (0018.14) |     ... Get sampling information
00:00:18 (0018.14) |     ... Statistics: 1984 of 2000 samples, 16 sec, 89.94 Ms/sec
00:00:18 (0018.14) |     ... Get buffers resolution
00:00:18 (0018.14) |     ... Resolution: 800 600
00:00:18 (0018.14) |     ... Check compositing
00:00:18 (0018.14) |     ... Compositing 0
00:00:18 (0018.14) |     ... Reading frame buffer
00:00:18 (0018.18) | ... Finish Rendering ...
00:00:18 (0018.18) | 
00:00:18 (0018.18) | ... wait for the getPreviewImage function ...
00:00:18 (0018.52) |     Reading preview image successfully
00:00:18 (0018.52) | [profile] Function "GetPreviewImage" over "" execution time: 0.375 seconds
00:00:18 (0018.52) |       >>> Refresh preview window
00:00:18 (0018.52) |       >>> Refresh preview window done
00:00:18 (0018.52) |       >>> processing image callback
00:00:18 (0018.52) |     Reading preview image
00:00:18 (0018.52) |     ... Get sampling information
00:00:18 (0018.52) |     ... Statistics: 2000 of 2000 samples, 16 sec, 0.00 Ms/sec
00:00:18 (0018.52) |     ... Get buffers resolution
00:00:18 (0018.52) |     ... Resolution: 800 600
00:00:18 (0018.52) |     ... Check compositing
00:00:18 (0018.52) |     ... Compositing 0
00:00:18 (0018.52) |     ... Reading frame buffer
00:00:18 (0018.98) |     Reading preview image successfully
00:00:18 (0018.98) | [profile] Function "GetPreviewImage" over "" execution time: 0.463 seconds
00:00:18 (0018.98) |       >>> Refresh preview window
00:00:18 (0018.98) |       >>> Refresh preview window done
00:00:18 (0018.98) | ... reset image callback ...
00:00:18 (0018.98) |         >>> Draw preview window information
00:00:18 (0018.98) |         >>> Draw preview window status
00:00:18 (0018.98) |         >>> Draw preview window progress bar
00:00:18 (0018.98) |         >>> Draw preview image
00:00:18 (0018.98) |         >>> Draw preview image finished. Releaseing mutex
00:00:18 (0018.98) | 
00:00:18 (0018.98) | Reading final HDRI image
00:00:18 (0018.98) |   ... Lock HDRI render buffers
00:00:19 (0019.19) |   ... Buffers ready, film size: 800 x 600, buffer size: 800 x 600
00:00:19 (0019.19) |   ... Composite state: 0
00:00:19 (0019.20) |   ... Image read ok
00:00:19 (0019.20) | Image read successfully
00:00:19 (0019.20) | 
00:00:19 (0019.20) | [profile] Function "GetImage" over "" execution time: 0.213 seconds
00:00:19 (0019.20) | ()()() Rendering done
00:00:19 (0019.26) |  <> FRAME 0 done, render time 17 seconds
00:00:19 (0019.26) |  <> Mem Used/Free/Total: 379 / 3275 / 4096
00:00:19 (0019.26) |  <> Triangles/DisplaceTris/Hairs/Objects/Voxels rendered: 12 / 0 / 7000 / 2 / 0
00:00:19 (0019.26) |  <> Textures RGB32/RGB64/GREY8/GREY16 used: 0 / 0 / 0 / 0
00:00:19 (0019.26) | 
00:00:19 (0019.26) | Close and free scene, free buffer: 0, reset scene: 1
00:00:19 (0019.26) |   ... setRenderTargetNode(NULL)
00:00:19 (0019.27) |   ... update()
00:00:19 (0019.27) |   ... getRootNodeGraph()->clear()
00:00:19 (0019.27) |       >>> Refresh preview window
00:00:19 (0019.27) |         >>> Draw preview window progress bar
00:00:19 (0019.28) |       >>> Refresh preview window done
00:00:19 (0019.28) | Scene closed
00:00:19 (0019.28) | 
00:00:19 (0019.28) | Close preview window
00:00:19 (0019.28) | 
00:00:19 (0019.28) | Octane Render for Lightwave, end of log system
