i've captured some output from progress report while rendering the scene, hope it can help u to understand more
//capture start here
JOB 0.12 progr: 60.6% rendered on x-tx004.12
JOB 0.15 progr: 61.5% rendered on x-tx004.15
JOB 0.25 progr: 62.3% rendered on x-tx004.25
JOB 0.24 progr: 63.2% rendered on x-tx004.24
JOB 0.6 progr: 64.1% rendered on x-tx004.6
JOB 0.21 progr: 64.9% rendered on x-tx004.21
JOB 0.7 progr: 65.8% rendered on x-tx004.7
JOB 0.5 progr: 66.6% rendered on x-tx004.5
RCH 0.4 info : RIGHT_HAIR6_Prim_7255_Geom bsp depth (max,min,avg.) : 20, 7, 19.0
RCH 0.4 info : RIGHT_HAIR6_Prim_7255_Geom leaf nodes (num,avg.size) : 34958, 5.3
RCH 0.4 info : RIGHT_HAIR6_Prim_7255_Geom cyls, runs, voxels : 242652, 187103, 35018
RCH 0.4 info : RIGHT_HAIR6_Prim_7255_Geom avg. cyl/run, cyl/voxel : 1.3, 6.9
RCH 0.4 info : RIGHT_HAIR6_Prim_7255_Geom memory max, final size : 10258 KB, 2578 KB
JOB 0.25 progr: 67.5% rendered on x-tx004.25
JOB 0.15 progr: 68.3% rendered on x-tx004.15
JOB 0.24 progr: 69.2% rendered on x-tx004.24
JOB 0.26 progr: 70.0% rendered on x-tx004.26
JOB 0.3 progr: 70.9% rendered on x-tx004.3
JOB 0.1 progr: job cache: wanted 164.5MB, got 0.0MB, new mem use 169.3MB
JOB 0.12 progr: 71.7% rendered on x-tx004.12
JOB 0.4 progr: 72.6% rendered on x-tx004.4
JOB 0.7 progr: 73.5% rendered on x-tx004.7
JOB 0.5 progr: 74.3% rendered on x-tx004.5
JOB 0.6 progr: 75.2% rendered on x-tx004.6
JOB 0.25 progr: 76.0% rendered on x-tx004.25
JOB 0.15 progr: 76.9% rendered on x-tx004.15
JOB 0.1 progr: job cache: wanted 164.5MB, got 0.1MB, new mem use 168.8MB
RCH 0.10 progr: RIGHT_HAIR5_Prim_7209_Geom bsp building...
JOB 0.26 progr: 77.7% rendered on x-tx004.26
JOB 0.24 progr: 78.6% rendered on x-tx004.24
JOB 0.4 progr: 79.4% rendered on x-tx004.4
JOB 0.12 progr: 80.3% rendered on x-tx004.12
JOB 0.3 progr: 81.1% rendered on x-tx004.3
RCH 0.13 info : RIGHT_HAIR4_Prim_7171_Geom bsp depth (max,min,avg.) : 20, 4, 19.1
RCH 0.13 info : RIGHT_HAIR4_Prim_7171_Geom leaf nodes (num,avg.size) : 36887, 6.3
RCH 0.13 info : RIGHT_HAIR4_Prim_7171_Geom cyls, runs, voxels : 286931, 232870, 36963
RCH 0.13 info : RIGHT_HAIR4_Prim_7171_Geom avg. cyl/run, cyl/voxel : 1.2, 7.8
RCH 0.13 info : RIGHT_HAIR4_Prim_7171_Geom memory max, final size : 12276 KB, 2992 KB
JOB 0.1 progr: job cache: wanted 171.5MB, got 0.1MB, new mem use 172.0MB
RCH 0.19 info : LEFT_HAIR2_Prim_6677_Geom bsp depth (max,min,avg.) : 20, 5, 19.2
RCH 0.19 info : LEFT_HAIR2_Prim_6677_Geom leaf nodes (num,avg.size) : 52790, 5.9
RCH 0.19 info : LEFT_HAIR2_Prim_6677_Geom cyls, runs, voxels : 367131, 312927, 52929
RCH 0.19 info : LEFT_HAIR2_Prim_6677_Geom avg. cyl/run, cyl/voxel : 1.2, 6.9
RCH 0.19 info : LEFT_HAIR2_Prim_6677_Geom memory max, final size : 16351 KB, 4119 KB
JOB 0.5 progr: 82.0% rendered on x-tx004.5
JOB 0.25 progr: 82.9% rendered on x-tx004.25
JOB 0.15 progr: 83.7% rendered on x-tx004.15
JOB 0.6 progr: 84.6% rendered on x-tx004.6
JOB 0.24 progr: 85.4% rendered on x-tx004.24
JOB 0.13 progr: 86.3% rendered on x-tx004.13
JOB 0.13 progr: job cache: wanted 171.4MB, got 0.1MB, new mem use 169.9MB
JOB 0.3 progr: 87.1% rendered on x-tx004.3
JOB 0.12 progr: 88.0% rendered on x-tx004.12
JOB 0.4 progr: 88.8% rendered on x-tx004.4
JOB 0.5 progr: 89.7% rendered on x-tx004.5
RCH 0.11 info : LEFT_HAIR6_Prim_6831_Geom bsp depth (max,min,avg.) : 20, 5, 19.1
RCH 0.11 info : LEFT_HAIR6_Prim_6831_Geom leaf nodes (num,avg.size) : 35233, 6.0
RCH 0.11 info : LEFT_HAIR6_Prim_6831_Geom cyls, runs, voxels : 268204, 211334, 35308
RCH 0.11 info : LEFT_HAIR6_Prim_6831_Geom avg. cyl/run, cyl/voxel : 1.3, 7.6
RCH 0.11 info : LEFT_HAIR6_Prim_6831_Geom memory max, final size : 11196 KB, 2777 KB
RCH 0.11 progr: LEFT_HAIR5_Prim_6791_Geom bsp building...
RCH 0.17 info : RIGHT_HAIR3_Prim_7133_Geom bsp depth (max,min,avg.) : 20, 4, 19.2
RCH 0.17 info : RIGHT_HAIR3_Prim_7133_Geom leaf nodes (num,avg.size) : 48096, 5.9
RCH 0.17 info : RIGHT_HAIR3_Prim_7133_Geom cyls, runs, voxels : 341187, 285177, 48211
RCH 0.17 info : RIGHT_HAIR3_Prim_7133_Geom avg. cyl/run, cyl/voxel : 1.2, 7.1
RCH 0.17 info : RIGHT_HAIR3_Prim_7133_Geom memory max, final size : 15020 KB, 3752 KB
RCH 0.14 info : TOP_HAIR3_Prim_7369_Geom bsp depth (max,min,avg.) : 20, 5, 19.2
RCH 0.14 info : TOP_HAIR3_Prim_7369_Geom leaf nodes (num,avg.size) : 50972, 6.8
RCH 0.14 info : TOP_HAIR3_Prim_7369_Geom cyls, runs, voxels : 409413, 347750, 51145
RCH 0.14 info : TOP_HAIR3_Prim_7369_Geom avg. cyl/run, cyl/voxel : 1.2, 8.0
RCH 0.14 info : TOP_HAIR3_Prim_7369_Geom memory max, final size : 17996 KB, 4327 KB
RCH 0.14 progr: TOP_HAIR1_Prim_7293_Geom bsp building...
RCH 0.10 info : RIGHT_HAIR5_Prim_7209_Geom bsp depth (max,min,avg.) : 20, 5, 19.0
RCH 0.10 info : RIGHT_HAIR5_Prim_7209_Geom leaf nodes (num,avg.size) : 45458, 5.2
RCH 0.10 info : RIGHT_HAIR5_Prim_7209_Geom cyls, runs, voxels : 290964, 236977, 45562
RCH 0.10 info : RIGHT_HAIR5_Prim_7209_Geom avg. cyl/run, cyl/voxel : 1.2, 6.4
RCH 0.10 info : RIGHT_HAIR5_Prim_7209_Geom memory max, final size : 13207 KB, 3293 KB
JOB 0.1 progr: job cache: wanted 180.1MB, got 0.0MB, new mem use 180.2MB
RCH 0.10 progr: RIGHT_EAR_Prim_6969_Geom bsp building...
RCH 0.11 info : LEFT_HAIR5_Prim_6791_Geom bsp depth (max,min,avg.) : 20, 6, 19.1
RCH 0.11 info : LEFT_HAIR5_Prim_6791_Geom leaf nodes (num,avg.size) : 45959, 5.7
RCH 0.11 info : LEFT_HAIR5_Prim_6791_Geom cyls, runs, voxels : 317813, 262291, 46064
RCH 0.11 info : LEFT_HAIR5_Prim_6791_Geom avg. cyl/run, cyl/voxel : 1.2, 6.9
RCH 0.11 info : LEFT_HAIR5_Prim_6791_Geom memory max, final size : 14365 KB, 3509 KB
RCH 0.23 info : LEFT_EAR_INSIDE_Prim_5917_Geom bsp depth (max,min,avg.) : 20, 4, 19.4
RCH 0.23 info : LEFT_EAR_INSIDE_Prim_5917_Geom leaf nodes (num,avg.size) : 49393, 10.1
RCH 0.23 info : LEFT_EAR_INSIDE_Prim_5917_Geom cyls, runs, voxels : 638187, 500086, 49431
RCH 0.23 info : LEFT_EAR_INSIDE_Prim_5917_Geom avg. cyl/run, cyl/voxel : 1.3, 12.9
RCH 0.23 info : LEFT_EAR_INSIDE_Prim_5917_Geom memory max, final size : 25303 KB, 5483 KB
RCH 0.14 info : TOP_HAIR1_Prim_7293_Geom bsp depth (max,min,avg.) : 20, 5, 19.0
RCH 0.14 info : TOP_HAIR1_Prim_7293_Geom leaf nodes (num,avg.size) : 50634, 6.3
RCH 0.14 info : TOP_HAIR1_Prim_7293_Geom cyls, runs, voxels : 383381, 319664, 50846
RCH 0.14 info : TOP_HAIR1_Prim_7293_Geom avg. cyl/run, cyl/voxel : 1.2, 7.5
RCH 0.14 info : TOP_HAIR1_Prim_7293_Geom memory max, final size : 17143 KB, 4095 KB
RCH 0.14 progr: RIGHT_EAR_INSIDE_Prim_7017_Geom bsp building...
RCH 0.14 info : RIGHT_EAR_INSIDE_Prim_7017_Geom bsp depth (max,min,avg.) : 20, 5, 19.3
RCH 0.14 info : RIGHT_EAR_INSIDE_Prim_7017_Geom leaf nodes (num,avg.size) : 40994, 6.4
RCH 0.14 info : RIGHT_EAR_INSIDE_Prim_7017_Geom cyls, runs, voxels : 335348, 263425, 41025
RCH 0.14 info : RIGHT_EAR_INSIDE_Prim_7017_Geom avg. cyl/run, cyl/voxel : 1.3, 8.2
RCH 0.14 info : RIGHT_EAR_INSIDE_Prim_7017_Geom memory max, final size : 14039 KB, 3357 KB
RCH 0.20 info : LEFT_EAR_Prim_4207_Geom bsp depth (max,min,avg.) : 20, 6, 19.4
RCH 0.20 info : LEFT_EAR_Prim_4207_Geom leaf nodes (num,avg.size) : 67824, 13.0
RCH 0.20 info : LEFT_EAR_Prim_4207_Geom cyls, runs, voxels : 1182870, 882033, 67865
RCH 0.20 info : LEFT_EAR_Prim_4207_Geom avg. cyl/run, cyl/voxel : 1.3, 17.4
RCH 0.20 info : LEFT_EAR_Prim_4207_Geom memory max, final size : 44312 KB, 9084 KB
RCH 0.19 progr: LEFT_HAIR1_Prim_5971_Geom bsp building...
JOB 0.16 progr: 90.5% rendered on x-tx004.16
JOB 0.1 progr: job cache: wanted 199.6MB, got 0.0MB, new mem use 198.8MB
RCH 0.20 progr: LEFT_HAIR4_Prim_6753_Geom bsp building...
RCH 0.19 info : LEFT_HAIR1_Prim_5971_Geom bsp depth (max,min,avg.) : 20, 4, 19.2
RCH 0.19 info : LEFT_HAIR1_Prim_5971_Geom leaf nodes (num,avg.size) : 44737, 5.9
RCH 0.19 info : LEFT_HAIR1_Prim_5971_Geom cyls, runs, voxels : 320996, 266026, 44818
RCH 0.19 info : LEFT_HAIR1_Prim_5971_Geom avg. cyl/run, cyl/voxel : 1.2, 7.2
RCH 0.19 info : LEFT_HAIR1_Prim_5971_Geom memory max, final size : 14373 KB, 3496 KB
JOB 0.19 progr: 91.4% rendered on x-tx004.19
RCH 0.20 info : LEFT_HAIR4_Prim_6753_Geom bsp depth (max,min,avg.) : 20, 5, 19.1
RCH 0.20 info : LEFT_HAIR4_Prim_6753_Geom leaf nodes (num,avg.size) : 39094, 6.0
RCH 0.20 info : LEFT_HAIR4_Prim_6753_Geom cyls, runs, voxels : 289657, 234087, 39183
RCH 0.20 info : LEFT_HAIR4_Prim_6753_Geom avg. cyl/run, cyl/voxel : 1.2, 7.4
RCH 0.20 info : LEFT_HAIR4_Prim_6753_Geom memory max, final size : 12199 KB, 3070 KB
RCH 0.17 progr: TOP_HAIR2_Prim_7331_Geom bsp building...
RCH 0.10 info : RIGHT_EAR_Prim_6969_Geom bsp depth (max,min,avg.) : 20, 6, 19.4
RCH 0.10 info : RIGHT_EAR_Prim_6969_Geom leaf nodes (num,avg.size) : 68944, 11.7
RCH 0.10 info : RIGHT_EAR_Prim_6969_Geom cyls, runs, voxels : 1111364, 809012, 68972
RCH 0.10 info : RIGHT_EAR_Prim_6969_Geom avg. cyl/run, cyl/voxel : 1.4, 16.1
RCH 0.10 info : RIGHT_EAR_Prim_6969_Geom memory max, final size : 40271 KB, 8551 KB
JOB 0.1 progr: job cache: wanted 209.8MB, got 0.2MB, new mem use 205.3MB
JOB 0.10 progr: 92.3% rendered on x-tx004.10
JOB 0.10 progr: job cache: wanted 209.6MB, got 0.2MB, new mem use 200.3MB
RCH 0.17 info : TOP_HAIR2_Prim_7331_Geom bsp depth (max,min,avg.) : 20, 4, 19.2
RCH 0.17 info : TOP_HAIR2_Prim_7331_Geom leaf nodes (num,avg.size) : 56727, 6.3
RCH 0.17 info : TOP_HAIR2_Prim_7331_Geom cyls, runs, voxels : 424393, 360189, 56906
RCH 0.17 info : TOP_HAIR2_Prim_7331_Geom avg. cyl/run, cyl/voxel : 1.2, 7.5
RCH 0.17 info : TOP_HAIR2_Prim_7331_Geom memory max, final size : 18770 KB, 4603 KB
RCH 0.17 progr: RIGHT_HAIR2_Prim_7095_Geom bsp building...
RCH 0.17 info : RIGHT_HAIR2_Prim_7095_Geom bsp depth (max,min,avg.) : 20, 4, 19.2
RCH 0.17 info : RIGHT_HAIR2_Prim_7095_Geom leaf nodes (num,avg.size) : 50942, 5.9
RCH 0.17 info : RIGHT_HAIR2_Prim_7095_Geom cyls, runs, voxels : 355909, 301583, 51059
RCH 0.17 info : RIGHT_HAIR2_Prim_7095_Geom avg. cyl/run, cyl/voxel : 1.2, 7.0
RCH 0.17 info : RIGHT_HAIR2_Prim_7095_Geom memory max, final size : 16409 KB, 3968 KB
RCH 0.17 progr: RIGHT_HAIR1_Prim_7057_Geom bsp building...
RCH 0.17 info : RIGHT_HAIR1_Prim_7057_Geom bsp depth (max,min,avg.) : 20, 4, 19.0
RCH 0.17 info : RIGHT_HAIR1_Prim_7057_Geom leaf nodes (num,avg.size) : 32405, 5.4
RCH 0.17 info : RIGHT_HAIR1_Prim_7057_Geom cyls, runs, voxels : 211343, 175768, 32465
RCH 0.17 info : RIGHT_HAIR1_Prim_7057_Geom avg. cyl/run, cyl/voxel : 1.2, 6.5
RCH 0.17 info : RIGHT_HAIR1_Prim_7057_Geom memory max, final size : 9463 KB, 2402 KB
MEM 0.9 fatal 031005: cannot free memory
//capture end here
best regards,
dt