SRT reconnection issues

[JanKoppe]

Hey,

now that Liquidsoap 2.0 seems to have much more support for video, it's getting interesting for me to use. I'm really new to Liquidsoap in general, so this could be some "you're holding it wrong!" issue, but I've stumbled upon this issue:

I've got a looping playlist of local video files, an SRT input, and a fallback switch that should pick the SRT input source whenever there's a signal coming in, otherwise fallback to the looping playlist. Seems pretty standard. This does work quite well for the first test: The looping playlist loops, as soon as I connect via OBS (27.0.1) with SRT, it switches over to that source, and when I disconnect OBS, it falls back to the looping playlist. But this will work only once: For the second connection, the log shows a weird assertion error, and the signal will not switch. When I then stop OBS completely, and restart it, the Liquidsoap logs show some errors from the SRT Library itself - seems like the source stays stuck and will not pick up new data.

Any idea If I'm doing something wrong here? Is this maybe a buggy behaviour?

main.liq

#!/usr/bin/env liquidsoap

settings.log.level.set(5)
settings.frame.audio.samplerate.set(48000)
settings.frame.video.width.set(1920)
settings.frame.video.height.set(1080)
settings.frame.video.framerate.set(60)

settings.frame.duration.set(0.05)

breakloop_playlist = mksafe(playlist(loop=true, "/data/loops"))
breakloop = mux_audio(audio=blank(), breakloop_playlist)

srt1 = input.srt(bind_address="0.0.0.0", port=20000, mode="listener")

pgm = fallback(track_sensitive=false, [srt1, breakloop])

encoder = %ffmpeg(
  format="mpegts",
  %audio(codec="aac", b="128k"),
  %video(codec="libx264", preset="fast", b="6000k")
)

output.srt(bind_address="0.0.0.0", port=20001, mode="listener", encoder, pgm)

command (2.0.0 docker image) & log:

$ docker run --rm -ti -v $PWD:/data --network host savonet/liquidsoap:v2.0.0 data/main.liq
2021/09/19 21:18:57 >>> LOG START
2021/09/19 21:18:57 [main:3] Liquidsoap 2.0.0
2021/09/19 21:18:57 [main:3] Using: bytes=[distributed with OCaml 4.02 or above] posix-time2=2.0.0 pcre=7.5.0 sedlex=2.4 menhirLib=20210419 curl=0.9.1 dtools=0.4.4 duppy=0.9.2 cry=0.6.5 mm=0.7.2 xmlplaylist=0.1.5 lastfm=0.3.3 ogg=0.7.0 ogg.decoder=0.7.0 vorbis=0.8.0 vorbis.decoder=0.8.0 opus=0.2.0 opus.decoder=0.2.0 speex=0.4.0 speex.decoder=0.4.0 mad=0.5.1 flac=0.3.0 flac.ogg=0.3.0 flac.decoder=0.3.0 dynlink=[distributed with Ocaml] lame=0.3.4 shine=0.2.2 frei0r=0.1.2 fdkaac=0.3.2 theora=0.4.0 theora.decoder=0.4.0 ffmpeg-avutil=1.0.1 ffmpeg-avcodec=1.0.1 ffmpeg-avdevice=1.0.1 ffmpeg-av=1.0.1 ffmpeg-avfilter=1.0.1 ffmpeg-swresample=1.0.1 ffmpeg-swscale=1.0.1 bjack=0.1.6 alsa=0.3.0 ao=0.2.3 samplerate=0.1.6 taglib=0.3.7 ssl=0.5.9 magic=0.7.3 camomile=1.0.2 inotify=2.3 yojson=1.7.0 faad=0.5.0 soundtouch=0.1.9 portaudio=0.2.2 pulseaudio=0.1.4 ladspa=0.2.0 dssi=0.1.3 tsdl=v0.9.8 tsdl_ttf=0 tsdl_image=0 camlimages=4.2.6 cohttp-lwt-unix=4.0.0 prometheus-app=1.1 srt.constants=0.2.0 srt.types=0.2.0 srt.stubs=0.2.0 srt.stubs.locked=0.2.0 srt=0.2.0 lo=0.2.0 gd=1.0a5
2021/09/19 21:18:57 [clock:3] Using native (high-precision) implementation for latency control
2021/09/19 21:18:57 [playlist_0:4] Reloading playlist.
2021/09/19 21:18:57 [playlist_0:4] Playlist is a directory.
2021/09/19 21:18:57 [frame:4] frame.audio.samplerate set to: 48000
2021/09/19 21:18:57 [frame:4] frame.video.framerate set to: 60
2021/09/19 21:18:57 [clock:4] Currently 1 clocks allocated.
2021/09/19 21:18:57 [frame:4] frame.audio.channels set to: 2
2021/09/19 21:18:57 [frame:4] frame.video.default set to: false
2021/09/19 21:18:57 [frame:4] frame.midi.channels set to: 0
2021/09/19 21:18:57 [frame:4] frame.video.width set to: 1920
2021/09/19 21:18:57 [frame:4] frame.video.height set to: 1080
2021/09/19 21:18:57 [frame:4] frame.audio.samplerate set to: 48000
2021/09/19 21:18:57 [frame:3] Using 48000Hz audio, 60Hz video, 48000Hz main.
2021/09/19 21:18:57 [frame:3] Video frame size set to: 1920x1080
2021/09/19 21:18:57 [frame:3] Frame size must be a multiple of 800 ticks = 800 audio samples = 1 video samples.
2021/09/19 21:18:57 [frame:3] Targeting 'frame.duration': 0.05s = 2400 audio samples = 2400 ticks.
2021/09/19 21:18:57 [frame:3] Frames last 0.05s = 2400 audio samples = 3 video samples = 2400 ticks.
2021/09/19 21:18:57 [sandbox:3] Sandboxing disabled
2021/09/19 21:18:57 [video.converter:3] Using preferred video converter: ffmpeg.
2021/09/19 21:18:57 [audio.converter:3] Using samplerate converter: ffmpeg.
2021/09/19 21:18:57 [lifecycle:5] At stage: "Liquidsoap application start"
2021/09/19 21:18:57 [clock:4] Currently 2 clocks allocated.
2021/09/19 21:18:57 [clock.mux_audio_0.child:4] Starting 2 sources...
2021/09/19 21:18:57 [source:4] Source video_main_0 gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [video_main_0:5] Clock is mux_audio_0.child[].
2021/09/19 21:18:57 [video_main_0:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:57 [video_main_0:4] Content type is {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [source:4] Source mksafe gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [source:4] Source playlist_0 gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [playlist_0:5] Clock is mux_audio_0.child[].
2021/09/19 21:18:57 [playlist_0:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:57 [playlist_0:4] Content type is {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [playlist_0:5] Activations changed: static=[], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:18:57 [source:4] Source safe_blank gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [safe_blank:5] Clock is mux_audio_0.child[].
2021/09/19 21:18:57 [safe_blank:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:57 [safe_blank:4] Content type is {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [safe_blank:5] Activations changed: static=[], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:18:57 [mksafe:5] Activations changed: static=[video_main_0:video_main_0], dynamic=[].
2021/09/19 21:18:57 [playlist_0:5] Queue is empty!
2021/09/19 21:18:57 [playlist_0:5] Failed to prepare track: no file.
2021/09/19 21:18:57 [video_main_0:5] Activations changed: static=[video_main_0], dynamic=[].
2021/09/19 21:18:57 [video_main_0:5] Enabling caching mode: active source.
2021/09/19 21:18:57 [source:4] Source audio_aux_0 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/09/19 21:18:57 [audio_aux_0:5] Clock is mux_audio_0.child[].
2021/09/19 21:18:57 [audio_aux_0:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/09/19 21:18:57 [audio_aux_0:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/09/19 21:18:57 [source:4] Source blank_2 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/09/19 21:18:57 [blank_2:5] Clock is mux_audio_0.child[].
2021/09/19 21:18:57 [blank_2:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/09/19 21:18:57 [blank_2:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/09/19 21:18:57 [blank_2:5] Activations changed: static=[audio_aux_0:audio_aux_0], dynamic=[].
2021/09/19 21:18:57 [audio_aux_0:5] Activations changed: static=[audio_aux_0], dynamic=[].
2021/09/19 21:18:57 [audio_aux_0:5] Enabling caching mode: active source.
2021/09/19 21:18:57 [clock.input.srt:4] Starting 2 sources...
2021/09/19 21:18:57 [source:4] Source input.srt_0 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:18:57 [input.srt_0:4] Setting up socket to listen at 0.0.0.0:20000
2021/09/19 21:18:57 [input.srt_0:5] Activations changed: static=[input.srt_0], dynamic=[].
2021/09/19 21:18:57 [input.srt_0:5] Enabling caching mode: active source.
2021/09/19 21:18:57 [source:4] Source srt_0 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:18:57 [output.srt:5] Clock is input.srt[mux_audio_0.child[]].
2021/09/19 21:18:57 [output.srt:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:18:57 [output.srt:4] Content type is {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:18:57 [source:4] Source switch_1 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:18:57 [input.srt_0:5] Activations changed: static=[input.srt_0], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:18:57 [source:4] Source mux_audio_0 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:18:57 [mux_audio_0:5] Clock is input.srt[mux_audio_0.child[]].
2021/09/19 21:18:57 [mux_audio_0:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:18:57 [mux_audio_0:4] Content type is {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:18:57 [video_main_0:5] Activations changed: static=[mux_audio_0, video_main_0], dynamic=[].
2021/09/19 21:18:57 [audio_aux_0:5] Activations changed: static=[mux_audio_0, audio_aux_0], dynamic=[].
2021/09/19 21:18:57 [mux_audio_0:5] Activations changed: static=[], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:18:57 [switch_1:5] Activations changed: static=[output.srt:output.srt], dynamic=[].
2021/09/19 21:18:57 [output.srt:4] Setting up socket to listen at 0.0.0.0:20001
2021/09/19 21:18:57 [output.srt:5] Activations changed: static=[output.srt], dynamic=[].
2021/09/19 21:18:57 [output.srt:5] Enabling caching mode: active source.
2021/09/19 21:18:57 [threads:4] Created thread "clock_input.srt" (1 total).
2021/09/19 21:18:57 [clock:4] Main phase starts.
2021/09/19 21:18:57 [threads:4] Created thread "generic queue #1" (1 total).
2021/09/19 21:18:57 [threads:4] Created thread "generic queue #2" (2 total).
2021/09/19 21:18:57 [threads:4] Created thread "non-blocking queue #1" (3 total).
2021/09/19 21:18:57 [threads:4] Created thread "non-blocking queue #2" (4 total).
2021/09/19 21:18:57 [clock.input.srt:3] Streaming loop starts in auto-sync mode
2021/09/19 21:18:57 [clock.input.srt:3] Delegating synchronisation to CPU clock
2021/09/19 21:18:57 [playlist_0:5] Next song will be "/data/loops/loop1.mp4".
2021/09/19 21:18:57 [request:5] Resolving request [[/data/loops/loop1.mp4]].
2021/09/19 21:18:57 [decoder.ogg:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:57 [decoder.ogg:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:57 [decoder:4] Available decoders: FFMPEG (priority: 10), IMAGE (priority: 1)
2021/09/19 21:18:57 [decoder.ffmpeg:4] ffmpeg recognizes /data/loops/loop1.mp4 as: video: {codec: h264, 1920x1080, yuv420p} and content-type: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [decoder:4] Selected decoder FFMPEG for file "/data/loops/loop1.mp4" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:57 [switch_1:3] Switch to mux_audio_0.
2021/09/19 21:18:57 [mux_audio_0:5] Activations changed: static=[switch_1:output.srt:output.srt], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:18:57 [mksafe:3] Switch to safe_blank.
2021/09/19 21:18:57 [safe_blank:5] Activations changed: static=[mksafe:video_main_0:video_main_0], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:18:57 [playlist_0:5] Queue is empty!
2021/09/19 21:18:57 [playlist_0:5] Failed to prepare track: no file.
2021/09/19 21:18:57 [metadata.flac:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:57 [metadata.flac:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:57 [metadata.mp4:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:57 [mksafe:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:57 [switch_1:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:18:57 [input.srt_0:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:18:57 [decoder.ogg:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:57 [decoder.ogg:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:57 [decoder.taglib:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:57 [decoder.taglib:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:57 [decoder.id3v2:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:57 [decoder.id3v2:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:57 [request:5] Resolved to [[/data/loops/loop1.mp4]].
2021/09/19 21:18:57 [request:5] Resolving request [[/data/loops/loop1.mp4]].
2021/09/19 21:18:57 [request:5] Resolved to [[/data/loops/loop1.mp4]].
2021/09/19 21:18:57 [playlist_0:4] Queued 1 requests
2021/09/19 21:18:57 [playlist_0:4] Remaining 0 requests
2021/09/19 21:18:57 [playlist_0:3] Prepared "/data/loops/loop1.mp4" (RID 0).
2021/09/19 21:18:57 [mksafe:3] Switch to playlist_0 with transition.
2021/09/19 21:18:57 [safe_blank:5] Activations changed: static=[], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:18:57 [source:4] Source sequence_0 gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [source:4] Source max_duration_0 gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:57 [playlist_0:5] Activations changed: static=[max_duration_0:sequence_0:mksafe:video_main_0:video_main_0], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:18:57 [max_duration_0:5] Activations changed: static=[sequence_0:mksafe:video_main_0:video_main_0], dynamic=[].
2021/09/19 21:18:57 [playlist_0:5] Activations changed: static=[sequence_0:mksafe:video_main_0:video_main_0, max_duration_0:sequence_0:mksafe:video_main_0:video_main_0], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:18:57 [playlist_0:5] Enabling caching mode: two static activations.
2021/09/19 21:18:57 [sequence_0:5] Activations changed: static=[mksafe:video_main_0:video_main_0], dynamic=[].
2021/09/19 21:18:57 [max_duration_0:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:57 [sequence_0:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:58 [playlist_0:5] Got metadata at position 0: calling handlers...
2021/09/19 21:18:58 [max_duration_0:5] Got metadata at position 0: calling handlers...
2021/09/19 21:18:58 [sequence_0:5] Got metadata at position 0: calling handlers...
2021/09/19 21:18:58 [mksafe:5] Got metadata at position 0: calling handlers...
2021/09/19 21:18:58 [playlist_0:5] Next song will be "/data/loops/loop1.mp4".
2021/09/19 21:18:58 [request:5] Resolving request [[/data/loops/loop1.mp4]].
2021/09/19 21:18:58 [decoder.ogg:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:58 [decoder.ogg:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:58 [decoder:4] Available decoders: FFMPEG (priority: 10), IMAGE (priority: 1)
2021/09/19 21:18:58 [mux_audio_0:5] Got metadata at position 0: calling handlers...
2021/09/19 21:18:58 [switch_1:5] Got metadata at position 0: calling handlers...
2021/09/19 21:18:58 [decoder.ffmpeg:4] ffmpeg recognizes /data/loops/loop1.mp4 as: video: {codec: h264, 1920x1080, yuv420p} and content-type: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:18:58 [decoder:4] Selected decoder FFMPEG for file "/data/loops/loop1.mp4" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/09/19 21:18:58 [metadata.flac:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:58 [metadata.flac:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:58 [metadata.mp4:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:58 [decoder.ogg:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:58 [decoder.ogg:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:58 [decoder.taglib:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:58 [decoder.taglib:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:58 [decoder.id3v2:4] Unsupported file extension for "/data/loops/loop1.mp4"!
2021/09/19 21:18:58 [decoder.id3v2:4] Unsupported MIME type for "/data/loops/loop1.mp4": video/mp4!
2021/09/19 21:18:58 [request:5] Resolved to [[/data/loops/loop1.mp4]].
2021/09/19 21:18:58 [request:5] Resolving request [[/data/loops/loop1.mp4]].
2021/09/19 21:18:58 [request:5] Resolved to [[/data/loops/loop1.mp4]].
2021/09/19 21:18:58 [playlist_0:4] Queued 1 requests
2021/09/19 21:18:59 [output.srt:4] New connection from 213.240.***.**:50786
2021/09/19 21:19:02 [playlist_0:5] Activations changed: static=[sequence_0:mksafe:video_main_0:video_main_0], dynamic=[mksafe:video_main_0:video_main_0].
2021/09/19 21:19:02 [playlist_0:5] Disabling caching mode.
2021/09/19 21:19:02 [source:4] Source fail_0 gets up with content kind: {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:19:02 [fail_0:5] Clock is ?(3fc6621fd4e4:0)[].
2021/09/19 21:19:02 [fail_0:5] Content kind: {audio=none,video=yuva420p,midi=none}, content type: {audio=none,video=yuva420p,midi=none}
2021/09/19 21:19:02 [fail_0:4] Content type is {audio=none,video=yuva420p,midi=none}.
2021/09/19 21:19:02 [fail_0:5] Activations changed: static=[max_duration_0], dynamic=[].
2021/09/19 21:19:07 [input.srt_0:4] New connection from 213.240.***.***:38979
2021/09/19 21:19:07 [switch_1:3] Switch to input.srt_0 with transition.
2021/09/19 21:19:07 [mux_audio_0:5] Activations changed: static=[], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:19:07 [source:4] Source sequence_1 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:07 [source:4] Source max_duration_1 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:07 [input.srt_0:5] Activations changed: static=[max_duration_1:sequence_1:switch_1:output.srt:output.srt, input.srt_0], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:19:07 [max_duration_1:5] Activations changed: static=[sequence_1:switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:07 [input.srt_0:5] Activations changed: static=[sequence_1:switch_1:output.srt:output.srt, max_duration_1:sequence_1:switch_1:output.srt:output.srt, input.srt_0], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:19:07 [sequence_1:5] Activations changed: static=[switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:07 [decoder:4] Available decoders:
2021/09/19 21:19:07 [decoder:4] FFMPEG (priority: 10)
2021/09/19 21:19:07 [decoder:4] Selected decoder FFMPEG for mime-type application/ffmpeg with expected content {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:08 [max_duration_1:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:08 [sequence_1:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:08 [clock.input.srt:3] Delegating synchronisation to active sources
2021/09/19 21:19:12 [input.srt_0:5] Activations changed: static=[sequence_1:switch_1:output.srt:output.srt, input.srt_0], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:19:12 [source:4] Source fail_1 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:12 [fail_1:5] Clock is ?(3fc66219d4d4:0)[].
2021/09/19 21:19:12 [fail_1:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:12 [fail_1:4] Content type is {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:12 [fail_1:5] Activations changed: static=[max_duration_1], dynamic=[].
2021/09/19 21:19:22 [input.srt_0:4] Feeding failed: Error(Econnlost,Connection was broken)
2021/09/19 21:19:22 [input.srt_0:4] Raised at Srt.check_err in file "src/srt.ml", line 73, characters 10-36
2021/09/19 21:19:22 [input.srt_0:4] Called from Srt.mk_recv in file "src/srt.ml", line 154, characters 15-42
2021/09/19 21:19:22 [input.srt_0:4] Called from Srt_io.input_base#create_decoder.read in file "io/srt_io.ml", line 800, characters 22-57
2021/09/19 21:19:22 [input.srt_0:4] Called from Av.read_input in file "av/av.ml", line 215, characters 2-31
2021/09/19 21:19:22 [input.srt_0:4] Called from Av.read_input in file "av/av.ml", line 215, characters 2-31
2021/09/19 21:19:22 [input.srt_0:4] Called from Ffmpeg_decoder.mk_decoder.(fun).f in file "decoder/ffmpeg_decoder.ml", line 655, characters 10-99
2021/09/19 21:19:22 [input.srt_0:4] Called from Srt_io.input_base#get_frame in file "io/srt_io.ml", line 827, characters 10-39
2021/09/19 21:19:22 [input.srt_0:4] 
2021/09/19 21:19:22 [input.srt_0:4] Setting up socket to listen at 0.0.0.0:20000
2021/09/19 21:19:22 [switch_1:3] Switch to mux_audio_0 with forgetful transition.
2021/09/19 21:19:22 [sequence_1:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:19:22 [source:5] Source sequence_1 gets down.
2021/09/19 21:19:22 [max_duration_1:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:19:22 [source:5] Source max_duration_1 gets down.
2021/09/19 21:19:22 [fail_1:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:19:22 [source:5] Source fail_1 gets down.
2021/09/19 21:19:22 [input.srt_0:5] Activations changed: static=[input.srt_0], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:19:22 [source:4] Source sequence_2 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:22 [source:4] Source max_duration_2 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:22 [source:4] Source replay_metadata_0 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:22 [replay_metadata_0:5] Clock is input.srt[mux_audio_0.child[]].
2021/09/19 21:19:22 [replay_metadata_0:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:22 [replay_metadata_0:4] Content type is {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:22 [mux_audio_0:5] Activations changed: static=[replay_metadata_0:max_duration_2:sequence_2:switch_1:output.srt:output.srt], dynamic=[switch_1:output.srt:output.srt].
2021/09/19 21:19:22 [replay_metadata_0:5] Activations changed: static=[max_duration_2:sequence_2:switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:22 [max_duration_2:5] Activations changed: static=[sequence_2:switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:22 [replay_metadata_0:5] Activations changed: static=[sequence_2:switch_1:output.srt:output.srt, max_duration_2:sequence_2:switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:22 [replay_metadata_0:5] Enabling caching mode: two static activations.
2021/09/19 21:19:22 [sequence_2:5] Activations changed: static=[switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:22 [replay_metadata_0:5] Got metadata at position 0: calling handlers...
2021/09/19 21:19:22 [max_duration_2:5] Got metadata at position 0: calling handlers...
2021/09/19 21:19:22 [sequence_2:5] Got metadata at position 0: calling handlers...
2021/09/19 21:19:22 [switch_1:5] Got metadata at position 0: calling handlers...
2021/09/19 21:19:22 [max_duration_2:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:22 [sequence_2:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:22 [clock.input.srt:3] Delegating synchronisation to CPU clock
2021/09/19 21:19:27 [replay_metadata_0:5] Activations changed: static=[sequence_2:switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:19:27 [replay_metadata_0:5] Disabling caching mode.
2021/09/19 21:19:27 [source:4] Source fail_3 gets up with content kind: {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:27 [fail_3:5] Clock is ?(3fc6621597cc:0)[].
2021/09/19 21:19:27 [fail_3:5] Content kind: {audio=pcm(stereo),video=yuva420p,midi=none}, content type: {audio=pcm(stereo),video=yuva420p,midi=none}
2021/09/19 21:19:27 [fail_3:4] Content type is {audio=pcm(stereo),video=yuva420p,midi=none}.
2021/09/19 21:19:27 [fail_3:5] Activations changed: static=[max_duration_2], dynamic=[].
2021/09/19 21:19:33 [input.srt_0:4] New connection from 213.240.***.***:41247
2021/09/19 21:19:33 [input.srt_0:5] Failed to connect: "Assert_failure stream/generator.ml:425:6"
2021/09/19 21:20:02 [srt:5] 21:20:02.999725/SRT:RcvQ:w1!W:SRT.cn: processConnectRequest: rsp(REJECT): 1005 - Listener's backlog exceeded
2021/09/19 21:20:11 [srt:5] 21:20:11.805543/SRT:RcvQ:w1!W:SRT.cn: processConnectRequest: rsp(REJECT): 1005 - Listener's backlog exceeded
^C2021/09/19 21:20:21 [main:3] Shutdown started!
2021/09/19 21:20:21 [lifecycle:5] At stage: "Liquidsoap core shutdown"
2021/09/19 21:20:21 [main:3] Waiting for main threads to terminate...
2021/09/19 21:20:21 [threads:4] Waiting for thread clock_input.srt to shutdown
2021/09/19 21:20:21 [output.srt:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source output.srt gets down.
2021/09/19 21:20:21 [switch_1:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source switch_1 gets down.
2021/09/19 21:20:21 [input.srt_0:5] Activations changed: static=[input.srt_0], dynamic=[].
2021/09/19 21:20:21 [mux_audio_0:5] Activations changed: static=[replay_metadata_0:max_duration_2:sequence_2:switch_1:output.srt:output.srt], dynamic=[].
2021/09/19 21:20:21 [sequence_2:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source sequence_2 gets down.
2021/09/19 21:20:21 [max_duration_2:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source max_duration_2 gets down.
2021/09/19 21:20:21 [fail_3:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source fail_3 gets down.
2021/09/19 21:20:21 [replay_metadata_0:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source replay_metadata_0 gets down.
2021/09/19 21:20:21 [mux_audio_0:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source mux_audio_0 gets down.
2021/09/19 21:20:21 [video_main_0:5] Activations changed: static=[video_main_0], dynamic=[].
2021/09/19 21:20:21 [audio_aux_0:5] Activations changed: static=[audio_aux_0], dynamic=[].
2021/09/19 21:20:21 [input.srt_0:5] Activations changed: static=[], dynamic=[].
2021/09/19 21:20:21 [source:5] Source input.srt_0 gets down.
2021/09/19 21:20:21 [clock.input.srt:3] Streaming loop stopped.
2021/09/19 21:20:21 [threads:4] Thread "clock_input.srt" terminated (0 remaining).
2021/09/19 21:20:21 [main:3] Main threads terminated.
2021/09/19 21:20:21 [lifecycle:5] At stage: "Liquidsoap scheduler shutdown"
2021/09/19 21:20:21 [threads:3] Shutting down scheduler...
2021/09/19 21:20:21 [threads:4] Thread "generic queue #1" terminated (3 remaining).
2021/09/19 21:20:21 [threads:4] Thread "non-blocking queue #1" terminated (2 remaining).
2021/09/19 21:20:21 [threads:4] Thread "generic queue #2" terminated (1 remaining).
2021/09/19 21:20:21 [threads:4] Thread "non-blocking queue #2" terminated (0 remaining).
2021/09/19 21:20:21 [threads:3] Scheduler shut down.
2021/09/19 21:20:21 [lifecycle:5] At stage: "Liquidsoap final cleanup"
[aac @ 0x7f8c87fdc480] 2 frames left in the queue on closing
2021/09/19 21:20:21 [main:3] Cleaning downloaded files...
2021/09/19 21:20:21 [main:3] Freeing memory...
2021/09/19 21:20:22 >>> LOG END

[toots]

Thanks for this report, this is exactly what we need right now. I'm making an issue out of it and will have a look as soon as possible!

[JanKoppe]

Thanks! I did a few more tests and this seems to be independent of OBS - I can replicate similar error behaviour with just ffmpeg. Let me know if I can be of any help with testing, etc. I'm awfully inexperienced with OCaml, so sadly won't be able to contribute any code :(

[JanKoppe]

#1186 might be related/the same?

[toots]

Issue is fixed, thanks for reporting!

[JanKoppe]

Yes, seems to be working perfectly, thank you so much for that fix :)