Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Question about performance drop on the cluster #950

Closed
Uio96 opened this issue Jul 30, 2023 · 6 comments
Closed

Question about performance drop on the cluster #950

Uio96 opened this issue Jul 30, 2023 · 6 comments
Labels
first answer provided question Question, not yet a bug ;)

Comments

@Uio96
Copy link

Uio96 commented Jul 30, 2023

Describe the issue

I have successfully configured the data generation pipeline on my local machine. Since I want to generate a larger dataset, I try to run the same script on the cluster with slurm.

The script can work, however, there is a huge speed drop (more than 10x) even if the GPU on the cluster is supposed to better than my local one.

  • I once assumed it might be a misconfiguration of CUDA. But when I tried CPU mode instead, the local machine's performance was still better the cluster one.
  • I also once doubted if that was an issue of I/O bottleneck, so I re-directed the output and tmp to another shared memory. It did not help much. The main bottleneck was still within the rendering process instead of writing files to some disks.
  • I also checked the minimal script of quickstart demo, the local machine will take 1.7s while the cluster one takes more than 5s. So it has nothing to do with my own script.

I am not sure there is any other issue may affect the performance so much. Has anyone had similar effort before? Thanks a lot.

Minimal code example

blenderproc quickstart

Files required to run the code

No response

Expected behavior

The cluster will take a lot more time to render the results compared to the local machine.

BlenderProc version

GitHub main branch

@Uio96 Uio96 added the question Question, not yet a bug ;) label Jul 30, 2023
@cornerfarmer
Copy link
Member

Can you check which part of the quickstart example is slower on your cluster? Especially the quickstart example does not take long to render, so the gpu choice will not make much of a difference. The other parts of the script mostly use the cpu, which is usually weaker on a gpu cluster compared to your local machine. So maybe that might be the reason why its slower.

@Uio96
Copy link
Author

Uio96 commented Jul 31, 2023

Can you check which part of the quickstart example is slower on your cluster? Especially the quickstart example does not take long to render, so the gpu choice will not make much of a difference. The other parts of the script mostly use the cpu, which is usually weaker on a gpu cluster compared to your local machine. So maybe that might be the reason why its slower.

Sure. The only bottleneck is within bpy.ops.render.render(animation=True, write_still=True) inside renderer/RendererUtility.py.

BTW, the cluster machines uses the CPU as my local one. I do not think it would cause so much difference.

(Update) Here is the output if I enable the verbose option (CPU rendering):

From local machine:

Device Quadro GP100 of type OPTIX found and used.
Device Quadro GP100 of type OPTIX found and used.
Device Intel Xeon CPU E5-2698 v4 @ 2.20GHz of type CPU found and used.
Selecting render devices...
Using only the CPU for rendering
Rendering 1 frames of colors...
Fra:0 Mem:8.74M (Peak 8.82M) | Time:00:00.00 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Synchronizing object | Suzanne
Fra:0 Mem:8.79M (Peak 8.82M) | Time:00:00.00 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Initializing
Fra:0 Mem:8.78M (Peak 8.82M) | Time:00:00.00 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Waiting for render to start
Fra:0 Mem:8.78M (Peak 8.82M) | Time:00:00.00 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Loading render kernels (may take a few minutes the first time)
Fra:0 Mem:8.78M (Peak 8.82M) | Time:00:00.00 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Scene
Fra:0 Mem:8.78M (Peak 8.82M) | Time:00:00.00 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Shaders
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Procedurals
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Background
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Camera
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Meshes Flags
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Objects
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Objects | Copying Transformations to device
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Objects | Applying Static Transformations
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Particle Systems
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Particle Systems | Copying Particles to device
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Meshes
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Mesh | Computing attributes
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Mesh | Copying Attributes to device
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Scene BVH | Building
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Scene BVH | Building BVH
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.05M, Peak:0.05M | Scene, ViewLayer | Updating Scene BVH | Building BVH 0%
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.09M, Peak:0.12M | Scene, ViewLayer | Updating Scene BVH | Copying BVH to device
Fra:0 Mem:9.30M (Peak 9.30M) | Time:00:00.01 | Mem:0.09M, Peak:0.12M | Scene, ViewLayer | Updating Mesh | Computing normals
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.09M, Peak:0.12M | Scene, ViewLayer | Updating Mesh | Copying Mesh to device
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Objects Flags
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Primitive Offsets
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Images
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Camera Volume
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Lookup Tables
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.40M, Peak:0.40M | Scene, ViewLayer | Updating Lights
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.40M, Peak:0.40M | Scene, ViewLayer | Updating Lights | Computing distribution
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.40M, Peak:0.40M | Scene, ViewLayer | Updating Integrator
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.01 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Updating Film
Fra:0 Mem:9.38M (Peak 9.63M) | Time:00:00.01 | Mem:0.16M, Peak:0.41M | Scene, ViewLayer | Updating Lookup Tables
Fra:0 Mem:9.38M (Peak 9.63M) | Time:00:00.01 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Updating Baking
Fra:0 Mem:9.38M (Peak 9.63M) | Time:00:00.01 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Updating Device | Writing constant memory
Fra:0 Mem:9.38M (Peak 9.63M) | Time:00:00.01 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Sample 0/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.02 | Remaining:00:12.73 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 1/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.26 | Remaining:00:02.95 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 80/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.28 | Remaining:00:02.60 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 96/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.30 | Remaining:00:02.35 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 112/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.31 | Remaining:00:02.13 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 128/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.33 | Remaining:00:01.95 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 144/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.34 | Remaining:00:01.81 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 160/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.36 | Remaining:00:01.68 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 176/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.37 | Remaining:00:01.57 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 192/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.39 | Remaining:00:01.47 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 208/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.40 | Remaining:00:01.38 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 224/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.41 | Remaining:00:01.30 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 240/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.42 | Remaining:00:01.23 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 256/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.43 | Remaining:00:01.17 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 272/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.45 | Remaining:00:01.11 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 288/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.46 | Remaining:00:01.05 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 304/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.47 | Remaining:00:01.00 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 320/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.48 | Remaining:00:00.95 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 336/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.49 | Remaining:00:00.91 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 352/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.50 | Remaining:00:00.87 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 368/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.51 | Remaining:00:00.82 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 384/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.51 | Remaining:00:00.78 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 400/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.52 | Remaining:00:00.75 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 416/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.53 | Remaining:00:00.71 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 432/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.54 | Remaining:00:00.68 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 448/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.55 | Remaining:00:00.65 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 464/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.56 | Remaining:00:00.62 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 480/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.56 | Remaining:00:00.59 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 496/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.57 | Remaining:00:00.56 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 512/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.58 | Remaining:00:00.53 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 528/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.59 | Remaining:00:00.50 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 544/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.59 | Remaining:00:00.48 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 560/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.60 | Remaining:00:00.45 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 576/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.61 | Remaining:00:00.43 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 592/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.61 | Remaining:00:00.41 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 608/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.62 | Remaining:00:00.38 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 624/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.62 | Remaining:00:00.36 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 640/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.63 | Remaining:00:00.34 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 656/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.63 | Remaining:00:00.32 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 672/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.64 | Remaining:00:00.30 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 688/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.64 | Remaining:00:00.28 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 704/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.65 | Remaining:00:00.26 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 720/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.65 | Remaining:00:00.25 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 736/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.66 | Remaining:00:00.23 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 752/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.66 | Remaining:00:00.21 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 768/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.67 | Remaining:00:00.20 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 784/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.67 | Remaining:00:00.18 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 800/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.68 | Remaining:00:00.17 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 816/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.68 | Remaining:00:00.15 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 832/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.69 | Remaining:00:00.14 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 848/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.69 | Remaining:00:00.12 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 864/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.70 | Remaining:00:00.11 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 880/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.70 | Remaining:00:00.09 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 896/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.70 | Remaining:00:00.11 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 880/1024 [2/1965]
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.70 | Remaining:00:00.09 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 896/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.71 | Remaining:00:00.08 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 912/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.71 | Remaining:00:00.07 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 928/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.71 | Remaining:00:00.05 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 944/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.72 | Remaining:00:00.04 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 960/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.72 | Remaining:00:00.03 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 976/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.73 | Remaining:00:00.02 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 992/1024
Fra:0 Mem:24.72M (Peak 24.72M) | Time:00:00.73 | Remaining:00:00.01 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 1008/1024
Fra:0 Mem:34.72M (Peak 48.73M) | Time:00:00.75 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 1024/1024
Fra:0 Mem:34.72M (Peak 48.73M) | Time:00:00.75 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Finished
Fra:0 Mem:34.73M (Peak 48.73M) | Time:00:00.75 | Compositing
Fra:0 Mem:34.73M (Peak 48.73M) | Time:00:00.75 | Compositing | Determining resolution
Fra:0 Mem:34.73M (Peak 48.73M) | Time:00:00.75 | Compositing | Initializing execution
Fra:0 Mem:72.80M (Peak 72.80M) | Time:00:01.00 | Compositing | Tile 1-4
Fra:0 Mem:72.80M (Peak 72.80M) | Time:00:01.00 | Compositing | Tile 2-4
Fra:0 Mem:72.80M (Peak 72.80M) | Time:00:01.00 | Compositing | Tile 3-4
Fra:0 Mem:72.80M (Peak 72.80M) | Time:00:01.00 | Compositing | Tile 4-4
Fra:0 Mem:72.74M (Peak 72.80M) | Time:00:01.00 | Compositing | De-initializing execution
Saved: '/dev/shm/blender_proc_377ae0015fe142b3a4f06f6050c1eeea/rgb_0000.png'
Time: 00:01.25 (Saving: 00:00.24)

Finished rendering after 1.253 seconds

From cluster machine:

Fra:0 Mem:8.75M (Peak 8.82M) | Time:00:00.02 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Synchronizing object | Suzanne
Fra:0 Mem:8.79M (Peak 8.82M) | Time:00:00.02 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Initializing
Fra:0 Mem:8.79M (Peak 8.82M) | Time:00:00.02 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Waiting for render to start
Fra:0 Mem:8.79M (Peak 8.82M) | Time:00:00.02 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Loading render kernels (may take a few minutes the first time)
Fra:0 Mem:8.79M (Peak 8.82M) | Time:00:00.02 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Scene
Fra:0 Mem:8.79M (Peak 8.82M) | Time:00:00.02 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Shaders
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Procedurals
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Background
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Camera
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Meshes Flags
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Objects
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Objects | Copying Transformations to device
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Objects | Applying Static Transformations
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Particle Systems
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Particle Systems | Copying Particles to device
Fra:0 Mem:9.29M (Peak 9.29M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Meshes
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Mesh | Computing attributes
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Mesh | Copying Attributes to device
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Scene BVH | Building
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.05 | Mem:0.00M, Peak:0.00M | Scene, ViewLayer | Updating Scene BVH | Building BVH
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.07 | Mem:0.05M, Peak:0.05M | Scene, ViewLayer | Updating Scene BVH | Building BVH 0%
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.07 | Mem:0.09M, Peak:0.12M | Scene, ViewLayer | Updating Scene BVH | Copying BVH to device
Fra:0 Mem:9.31M (Peak 9.31M) | Time:00:00.07 | Mem:0.09M, Peak:0.12M | Scene, ViewLayer | Updating Mesh | Computing normals
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.09M, Peak:0.12M | Scene, ViewLayer | Updating Mesh | Copying Mesh to device
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Objects Flags
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Primitive Offsets
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Images
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Camera Volume
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.15M, Peak:0.15M | Scene, ViewLayer | Updating Lookup Tables
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.40M, Peak:0.40M | Scene, ViewLayer | Updating Lights
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.40M, Peak:0.40M | Scene, ViewLayer | Updating Lights | Computing distribution
Fra:0 Mem:9.37M (Peak 9.37M) | Time:00:00.07 | Mem:0.40M, Peak:0.40M | Scene, ViewLayer | Updating Integrator
Fra:0 Mem:9.38M (Peak 9.38M) | Time:00:00.07 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Updating Film
Fra:0 Mem:9.38M (Peak 9.64M) | Time:00:00.07 | Mem:0.16M, Peak:0.41M | Scene, ViewLayer | Updating Lookup Tables
Fra:0 Mem:9.38M (Peak 9.64M) | Time:00:00.07 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Updating Baking
Fra:0 Mem:9.38M (Peak 9.64M) | Time:00:00.07 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Updating Device | Writing constant memory
Fra:0 Mem:9.38M (Peak 9.64M) | Time:00:00.07 | Mem:0.41M, Peak:0.41M | Scene, ViewLayer | Sample 0/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:00.12 | Remaining:00:46.52 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 1/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:01.94 | Remaining:00:22.10 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 80/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.08 | Remaining:00:19.40 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 96/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.20 | Remaining:00:17.36 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 112/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.32 | Remaining:00:15.74 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 128/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.43 | Remaining:00:14.42 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 144/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.53 | Remaining:00:13.29 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 160/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.63 | Remaining:00:12.33 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 176/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.73 | Remaining:00:11.52 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 192/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.83 | Remaining:00:10.80 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 208/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.91 | Remaining:00:10.14 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 224/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:02.99 | Remaining:00:09.53 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 240/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.07 | Remaining:00:08.99 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 256/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.14 | Remaining:00:08.48 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 272/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.21 | Remaining:00:08.02 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 288/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.28 | Remaining:00:07.60 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 304/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.35 | Remaining:00:07.20 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 320/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.41 | Remaining:00:06.83 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 336/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.47 | Remaining:00:06.48 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 352/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.53 | Remaining:00:06.16 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 368/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.58 | Remaining:00:05.85 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 384/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.64 | Remaining:00:05.55 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 400/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.69 | Remaining:00:05.28 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 416/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.74 | Remaining:00:05.02 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 432/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.79 | Remaining:00:04.77 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 448/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.84 | Remaining:00:04.54 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 464/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.88 | Remaining:00:04.31 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 480/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.93 | Remaining:00:04.10 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 496/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:03.97 | Remaining:00:03.89 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 512/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.01 | Remaining:00:03.69 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 528/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.05 | Remaining:00:03.50 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 544/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.09 | Remaining:00:03.32 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 560/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.12 | Remaining:00:03.15 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 576/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.16 | Remaining:00:02.98 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 592/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.20 | Remaining:00:02.82 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 608/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.23 | Remaining:00:02.66 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 624/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.27 | Remaining:00:02.51 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 640/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.30 | Remaining:00:02.37 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 656/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.33 | Remaining:00:02.22 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 672/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.36 | Remaining:00:02.09 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 688/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.38 | Remaining:00:01.96 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 704/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.41 | Remaining:00:01.83 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 720/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.44 | Remaining:00:01.70 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 736/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.46 | Remaining:00:01.58 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 752/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.49 | Remaining:00:01.47 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 768/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.52 | Remaining:00:01.36 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 784/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.54 | Remaining:00:01.25 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 800/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.56 | Remaining:00:01.14 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 816/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.59 | Remaining:00:01.04 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 832/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.61 | Remaining:00:00.94 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 848/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.63 | Remaining:00:00.84 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 864/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.65 | Remaining:00:00.74 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 880/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.68 | Remaining:00:00.65 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 896/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.70 | Remaining:00:00.56 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 912/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.72 | Remaining:00:00.48 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 928/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.74 | Remaining:00:00.39 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 944/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.76 | Remaining:00:00.31 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 960/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.78 | Remaining:00:00.23 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 976/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.79 | Remaining:00:00.15 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 992/1024
Fra:0 Mem:24.43M (Peak 24.43M) | Time:00:04.81 | Remaining:00:00.07 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 1008/1024
Fra:0 Mem:34.43M (Peak 48.43M) | Time:00:04.85 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Sample 1024/1024
Fra:0 Mem:34.43M (Peak 48.43M) | Time:00:04.85 | Mem:15.41M, Peak:15.41M | Scene, ViewLayer | Finished
Fra:0 Mem:34.44M (Peak 48.43M) | Time:00:04.85 | Compositing
Fra:0 Mem:34.44M (Peak 48.43M) | Time:00:04.86 | Compositing | Determining resolution
Fra:0 Mem:34.44M (Peak 48.43M) | Time:00:04.86 | Compositing | Initializing execution
Fra:0 Mem:72.51M (Peak 72.51M) | Time:00:05.91 | Compositing | Tile 1-4
Fra:0 Mem:72.51M (Peak 72.51M) | Time:00:05.91 | Compositing | Tile 2-4
Fra:0 Mem:72.51M (Peak 72.51M) | Time:00:05.91 | Compositing | Tile 3-4
Fra:0 Mem:72.51M (Peak 72.51M) | Time:00:05.91 | Compositing | Tile 4-4
Fra:0 Mem:72.44M (Peak 72.51M) | Time:00:05.91 | Compositing | De-initializing execution
Saved: '/dev/shm/blender_proc_aed4ff2e1f0c488bb64f99da2c83db6d/rgb_0000.png'
Time: 00:06.24 (Saving: 00:00.33)

Selecting render devices...
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Tesla V100-SXM2-16GB of type OPTIX found and used.
Device Intel Xeon CPU E5-2698 v4 @ 2.20GHz of type CPU found and used.
Selecting render devices...
Using only the CPU for rendering
Rendering 1 frames of colors...
Finished rendering after 6.250 seconds

@cornerfarmer
Copy link
Member

cornerfarmer commented Jul 31, 2023

Hey @Uio96,

it seems that you are using all 8 gpus at once on the cluster machine. If you are using something like slurm, make sure to only reserve 1 GPU for your job. Alternatively, you can also use desired_gpu_ids=0 in bproc.renderer.set_render_devices() to only use the first gpu.

Using multiple gpus at once can speed up rendering of big scenes, but for small scenes it creates usually more overhead. So could you try whether using only one gpu resolves your issue?

Of course its still strange that the render time is so different when using cpu only mode...

@Uio96
Copy link
Author

Uio96 commented Jul 31, 2023

Hey @Uio96,

it seems that you are using all 8 gpus at once on the cluster machine. If you are using something like slurm, make sure to only reserve 1 GPU for your job. Alternatively, you can also use desired_gpu_ids=0 in bproc.renderer.set_render_devices() to only use the first gpu.

Using multiple gpus at once can speed up rendering of big scenes, but for small scenes it creates usually more overhead. So could you try whether using only one gpu resolves your issue?

Of course its still strange that the render time is so different when using cpu only mode...

Thanks for the reply. But I already enabled the CPU rendering ("Using only the CPU for rendering") by changing bproc.renderer.set_render_devices(True) in the case I showed.

I did try setting up the available GPU to the process before (e.g., one GPU available per process), but no help. (The result of using a single GPU is similar, costs around 5s, still several times slower than the local one.)

@cornerfarmer
Copy link
Member

Hmm, this is really strange. It seems to be specific to your system.
I just tried a similar setup and always get around 1s render time.

Selecting render devices...
Device NVIDIA A100-SXM4-80GB of type OPTIX found and used.
Device Intel Xeon Gold 6336Y CPU @ 2.40GHz of type CPU found and used.
Rendering 1 frames of colors...
Finished rendering after 1.457 seconds

There is not really something I can do here.
One last thing you can try is to use GPU-only rendering, by using

bproc.renderer.set_render_devices(desired_gpu_ids=0)

Maybe the cpu is slowing it down on your system.

@Uio96
Copy link
Author

Uio96 commented Jul 31, 2023

Thank you so much for the help. I do not think the issue comes from Blender or Blenderporc. I tried the cluster machines with the exact same spec (interactive node and submit node), they also had performance disparity.

I will ask the administrator to see if there is anything special about the setup or I will just scale up the nodes to generate data.

@Uio96 Uio96 closed this as completed Jul 31, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
first answer provided question Question, not yet a bug ;)
Projects
None yet
Development

No branches or pull requests

2 participants