butler.cc:130

Anything and everything to do with DCP-o-matic.
Post Reply
ManyMen49
Posts: 3
Joined: Tue Aug 25, 2020 6:07 pm

butler.cc:130

Post by ManyMen49 »

Hello everyone!

I have not found any topic for this specific issue.

When converting or playing it via the build in player, following Error appears

"Ein Fehler ist aufgetreten.
Programmfehler bei ../src/lib/butler.cc:130
Butler video buffers reached 18719 frames (audio is 55171 at 10574068)"

Confirming it, shuts down the programm.

Version 2.14.13
For a update, i would have to wait until thursday, if thats the only reason.
But maybe there is another solution for this?

Hints and Help appreciated.
Much love for this tool and the people working on it <3

ManyM49
carl
Site Admin
Posts: 2338
Joined: Thu Nov 14, 2013 2:53 pm

Re: butler.cc:130

Post by carl »

There are unfortunately a few bugs that cause messages like this, and an update is the best first step.

But also you could tell us about what file you are converting. If you find "metadata.xml" and "ffprobe.log" files inside your DCP-o-matic project folder, please send them to me at carl@dcpomatic.com
ManyMen49
Posts: 3
Joined: Tue Aug 25, 2020 6:07 pm

Re: butler.cc:130

Post by ManyMen49 »

Thanks for the reply!

Currently I only have restricted access to the workstation.

Please keep this topic open, I will give you a update on the matter asap.

MM49
ManyMen49
Posts: 3
Joined: Tue Aug 25, 2020 6:07 pm

Re: butler.cc:130

Post by ManyMen49 »

Hi again!

I won't open a thread again, until I have not updated to the latest release.

Update fixed the issue. Thread can be closed.

MM49
mrdvd030
Posts: 1
Joined: Sat Nov 27, 2021 1:42 pm

Re: butler.cc:130

Post by mrdvd030 »

Hi to all,
And thanks for the question. I have something similar happening. The DCP computation
looks ok but while reading the DCP file in the DCP-o-matic software, the video and sound are not synchronized, the sound precedes the video at the
beginning of the file. Towards the end it seems they are almost sync. Problem with the reader itself? Is the DCP problematic in itself?
Here is the log: (partial)

27/11/2021 12:09:26: New graph for 1280x720, pixel format 0
27/11/2021 12:09:26: New graph for 1280x720, pixel format 0
27/11/2021 12:12:51: New graph for 1280x720, pixel format 0
27/11/2021 12:12:52: New graph for 1280x720, pixel format 0
27/11/2021 12:12:53: New graph for 1280x720, pixel format 0
27/11/2021 12:12:53: New graph for 1280x720, pixel format 0
27/11/2021 12:12:54: New graph for 1280x720, pixel format 0
27/11/2021 12:12:55: New graph for 1280x720, pixel format 0
27/11/2021 12:12:56: New graph for 1280x720, pixel format 0
27/11/2021 12:12:56: New graph for 1280x720, pixel format 0
27/11/2021 12:16:41: New graph for 1280x720, pixel format 0
27/11/2021 12:16:42: New graph for 1280x720, pixel format 0
27/11/2021 12:16:42: New graph for 1280x720, pixel format 0
27/11/2021 12:16:43: New graph for 1280x720, pixel format 0
27/11/2021 12:16:44: New graph for 1280x720, pixel format 0
27/11/2021 12:16:44: New graph for 1280x720, pixel format 0
27/11/2021 12:16:45: New graph for 1280x720, pixel format 0
27/11/2021 12:16:46: New graph for 1280x720, pixel format 0
27/11/2021 12:16:47: New graph for 1280x720, pixel format 0
27/11/2021 12:16:49: New graph for 1280x720, pixel format 0
27/11/2021 12:16:50: New graph for 1280x720, pixel format 0
27/11/2021 12:16:52: New graph for 1280x720, pixel format 0
27/11/2021 12:16:54: New graph for 1280x720, pixel format 0
27/11/2021 12:16:56: New graph for 1280x720, pixel format 0
27/11/2021 12:16:58: New graph for 1280x720, pixel format 0
27/11/2021 12:19:50: DCP-o-matic 2.14.56 git 1a0bf54684 using libavcodec 58.42.100, libavfilter 7.46.101, libavformat 58.24.100, libavutil 56.24.101, libswscale 5.4.100, libssh 0.7.5/gnutls/zlib, libdcp 1.6.18 git 575129cca8
27/11/2021 12:19:50: Host name LAPTOP-QG4B30D3
27/11/2021 12:19:50: DCP-o-matic built in optimised mode.
27/11/2021 12:19:50: libdcp built in optimised mode.
27/11/2021 12:19:50: Windows version 6.2.9200
27/11/2021 12:19:50: Windows 8 or Windows Server 2012
27/11/2021 12:19:50: Built for 64-bit
27/11/2021 12:19:50: CPU: AMD Ryzen 7 4700U with Radeon Graphics
27/11/2021 12:19:50: Content: demo_jilal_mechta.mp4 20ca51919874071e9490d997819bf2d933404572 0 25 - video: length 5025 frames, size 1280x720, sample aspect ratio 1 - audio: stream channels 2 rate 48000 ffmpeg: audio id 2 subtitle none filters
27/11/2021 12:19:50: DCP video rate 25 fps
27/11/2021 12:19:50: 8 threads
27/11/2021 12:19:50: J2K bandwidth 150000000
27/11/2021 12:19:50: Transcode job starting
27/11/2021 12:19:50: Sub-job Recherche de données images existantes starting
27/11/2021 12:19:50: Opened existing asset at C:\Users\33766\Desktop\stuff\projets cinéma\Lulu\montage\film format DCP\lulu\Lulu\video\185_2K_a929119f50bb063f30406ea0917282d8_25_150000000_P_I_0_19296000.mxf
27/11/2021 12:19:50: The last FI is 5024; info file is 241200, info size 48
27/11/2021 12:19:50: Checking existing picture frame 5024
27/11/2021 12:19:50: Read 152132 bytes of asset data; wanted 152132
27/11/2021 12:19:50: Hash 3ce44e91319f60ea529e00da2876a93b vs 3ce44e91319f60ea529e00da2876a93b
27/11/2021 12:19:50: Proceeding with first nonexistant frame 5025
27/11/2021 12:19:50: Sub-job Encodage starting
27/11/2021 12:19:50: New graph for 1280x720, pixel format 0
27/11/2021 12:20:06: Clearing queue of 0
27/11/2021 12:20:06: Terminating encoder threads
27/11/2021 12:20:06: Terminating thread 1 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 2 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 3 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 4 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 5 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 6 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 7 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Terminating thread 8 of 8
27/11/2021 12:20:06: Thread terminated
27/11/2021 12:20:06: Mopping up 0
27/11/2021 12:20:06: Terminating writer thread
27/11/2021 12:20:06: Finishing ReelWriters
27/11/2021 12:20:06: Must put C:\Users\33766\Desktop\stuff\projets cinéma\Lulu\montage\film format DCP\lulu\Lulu\video\185_2K_a929119f50bb063f30406ea0917282d8_25_150000000_P_I_0_19296000.mxf into DCP at C:\Users\33766\Desktop\stuff\projets cinéma\Lulu\montage\film format DCP\lulu\Lulu\Lulu_SHR-1-25_F-178_20_2K_20211127_IOP_OV\j2c_18e189a6-71a6-4a22-a3aa-80ed3ff639e9.mxf
27/11/2021 12:20:06: C:\Users\33766\Desktop\stuff\projets cinéma\Lulu\montage\film format DCP\lulu\Lulu\video\185_2K_a929119f50bb063f30406ea0917282d8_25_150000000_P_I_0_19296000.mxf: yes
27/11/2021 12:20:06: C:\Users\33766\Desktop\stuff\projets cinéma\Lulu\montage\film format DCP\lulu\Lulu\Lulu_SHR-1-25_F-178_20_2K_20211127_IOP_OV\j2c_18e189a6-71a6-4a22-a3aa-80ed3ff639e9.mxf: no
27/11/2021 12:20:06: Writing XML
27/11/2021 12:20:06: Sub-job sommes de calcul en cours starting
27/11/2021 12:20:12: create_reel for 0-19296000; 0 of 1
27/11/2021 12:20:12: create_reel for 0-19296000; 0 of 1
27/11/2021 12:20:12: Wrote 1 FULL, 5024 FAKE, 0 REPEAT, 0 pushed to disk
27/11/2021 12:20:12: Transcode job completed successfully: 228 fps
27/11/2021 12:22:25: New graph for 1280x720, pixel format 0
27/11/2021 12:22:27: New graph for 1280x720, pixel format 0
27/11/2021 12:22:28: WARNING: Butler video buffers reached 96 frames (audio is 4096)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 96 frames (audio is 5120)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 96 frames (audio is 6144)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 96 frames (audio is 6144)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 96 frames (audio is 7168)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 96 frames (audio is 8192)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 97 frames (audio is 8192)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 97 frames (audio is 9216)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 97 frames (audio is 10240)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 98 frames (audio is 10240)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 98 frames (audio is 11264)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 98 frames (audio is 12288)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 99 frames (audio is 12288)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 99 frames (audio is 13312)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 99 frames (audio is 14336)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 99 frames (audio is 14336)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 99 frames (audio is 15360)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 100 frames (audio is 0)
27/11/2021 12:22:28: WARNING: Butler video buffers reached 100 frames (audio is 1024)

thanks guys. Hope this also helps some.
Post Reply