Liquidsoap seem to be streaming into nowhere after automatic restart after error

[StarveTheEgo]

Describe the bug

I am streaming to youtube via liquidoap After switching to master/main branches, i am getting same issue over and over for last 18 months or something

After absolutely random time of continous streaming, Youtube just stops my stream for some reason, even if it notices current bitrate and excellent connection, it still asks me to connect my streaming software

At same time, liquidsoap writes in the logs, like it works fine, shows current song and image metadata Recently i managed to catch some error in the logs (broken pipe), which might help you to locate the issue! :-P Here is some log part with big context:

2021/05/26 10:08:22 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:current
2021/05/26 10:08:22 [request:5] Resolving request [[annotate:image_link="https://unsplash.com/photos/25yFm13hcp4",author="Michael Scherback",profile_link="https://unsplash.com/@startliving67",description="Ghost town of Gleeson AZ with the Milkyway",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg]].
2021/05/26 10:08:22 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"!
2021/05/26 10:08:22 [decoder.ffmpeg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"!
2021/05/26 10:08:22 [decoder:4] Available decoders: IMAGE (priority: 1)
2021/05/26 10:08:22 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"...
2021/05/26 10:08:22 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg".
2021/05/26 10:08:22 [decoder:4] Selected decoder IMAGE for file "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/05/26 10:08:22 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"!
2021/05/26 10:08:22 [decoder.taglib:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"!
2021/05/26 10:08:22 [decoder.id3v2:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"!
2021/05/26 10:08:22 [request:5] Resolved to [[/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg], [annotate:image_link="https://unsplash.com/photos/25yFm13hcp4",author="Michael Scherback",profile_link="https://unsplash.com/@startliving67",description="Ghost town of Gleeson AZ with the Milkyway",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg]].
2021/05/26 10:08:22 [deer_image:4] Remaining: 9.6s, queued: 0.0s, adding: 30.0s (RID 6)
2021/05/26 10:08:28 [decoder:4] Decoding "/var/www/deer-radio.ru/storage/app/public/songs/January2019/IpizqnjSfEbTl1Lzgpj8.mp3" ended: Ffmpeg_decoder.End_of_file.
Raised at file "decoder/ffmpeg_decoder.ml", line 287, characters 12-29
Called from file "decoder/decoder.ml", line 494, characters 10-31

2021/05/26 10:08:28 [audio_stream:4] Finished with "/var/www/deer-radio.ru/storage/app/public/songs/January2019/IpizqnjSfEbTl1Lzgpj8.mp3".
2021/05/26 10:08:28 [amplify_29164:4] End of the current overriding.
2021/05/26 10:08:28 [audio_stream:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2021/05/26 10:08:28 [audio_stream:3] Prepared "/var/www/deer-radio.ru/storage/app/public/songs/February2019/TkmdxNeWcxgLCYJUKn9l.flac" (RID 0).
2021/05/26 10:08:28 [audio_stream:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:28 [blank.detect_29133:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:28 [amplify_29164:4] Overriding amplification: 1.000000.
2021/05/26 10:08:28 [amplify_29164:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:28 [lang:3] ["https://neurotech.bandcamp.com"]
2021/05/26 10:08:28 [lang:3] []
2021/05/26 10:08:28 [lang:3] Now playing Unleashing the Dead by Neurotech
2021/05/26 10:08:28 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan song:updateNowPlaying 200
2021/05/26 10:08:28 [lang:3] actually playing
2021/05/26 10:08:28 [lang:3] updating deer image
2021/05/26 10:08:28 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:update
2021/05/26 10:08:30 [lang:3] updating deer image
2021/05/26 10:08:30 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:update
2021/05/26 10:08:30 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan stream-chat:notify 'Song: Neurotech - Unleashing the Dead | You can find more info about Neurotech at: https://neurotech.bandcamp.com'
2021/05/26 10:08:31 [lang:3] Successfully sent notification: Song: Neurotech - Unleashing the Dead | You can find more info about Neurotech at: https://neurotech.bandcamp.com
2021/05/26 10:08:31 [cross_29193:3] Analysis: -79.177679dB / -38.301208dB (4.96s / 4.96s)
2021/05/26 10:08:31 [crossfade:4] Before: ("tcon", "Psybient / Downtempo")
2021/05/26 10:08:31 [crossfade:4] Before: ("album", "Era2")
2021/05/26 10:08:31 [crossfade:4] Before: ("author_links", "[\"http://dreamstatelogic.com\",\"https://dreamstatelogic.bandcamp.com\",\"https://www.youtube.com/dreamstatelogic\",\"https://soundcloud.com/dreamstatelogic\",\"https://www.facebook.com/dreamstatelogic\",\"https://www.instagram.com/dreamstatelogic\",\"https://open.spotify.com/artist/28v6aJcwl6Wvm6MH6gINzN\"]")
2021/05/26 10:08:31 [crossfade:4] Before: ("date", "2016")
2021/05/26 10:08:31 [crossfade:4] Before: ("tempo", "90")
2021/05/26 10:08:31 [crossfade:4] Before: ("encoded_by", "LAME in FL Studio 12")
2021/05/26 10:08:31 [crossfade:4] Before: ("kind", "{audio=pcm(stereo),video=none,midi=none}")
2021/05/26 10:08:31 [crossfade:4] Before: ("year", "2016")
2021/05/26 10:08:31 [crossfade:4] Before: ("artist", "Dreamstate Logic")
2021/05/26 10:08:31 [crossfade:4] Before: ("decoder", "FFMPEG")
2021/05/26 10:08:31 [crossfade:4] Before: ("title", "Across The Void")
2021/05/26 10:08:31 [crossfade:4] Before: ("bpm", "90")
2021/05/26 10:08:31 [crossfade:4] Before: ("label", "")
2021/05/26 10:08:31 [crossfade:4] Before: ("filename", "/var/www/deer-radio.ru/storage/app/public/songs/January2019/IpizqnjSfEbTl1Lzgpj8.mp3")
2021/05/26 10:08:31 [crossfade:4] Before: ("id", "70")
2021/05/26 10:08:31 [crossfade:4] Before: ("temporary", "false")
2021/05/26 10:08:31 [crossfade:4] Before: ("source", "audio_stream")
2021/05/26 10:08:31 [crossfade:4] Before: ("initial_uri", "annotate:id=\"70\",artist=\"Dreamstate Logic\",title=\"Across The Void\",replay_gain=\"1.00\",album=\"Era2\",album_year=\"2016\",artist_website=\"http://dreamstatelogic.com\",author_links=\"[\\\"http:\\/\\/dreamstatelogic.com\\\",\\\"https:\\/\\/dreamstatelogic.bandcamp.com\\\",\\\"https:\\/\\/www.youtube.com\\/dreamstatelogic\\\",\\\"https:\\/\\/soundcloud.com\\/dreamstatelogic\\\",\\\"https:\\/\\/www.facebook.com\\/dreamstatelogic\\\",\\\"https:\\/\\/www.instagram.com\\/dreamstatelogic\\\",\\\"https:\\/\\/open.spotify.com\\/artist\\/28v6aJcwl6Wvm6MH6gINzN\\\"]\",label=\"\",label_website=\"\",label_links=\"[]\":file:///var/www/deer-radio.ru/storage/app/public/songs/January2019/IpizqnjSfEbTl1Lzgpj8.mp3")
2021/05/26 10:08:31 [crossfade:4] Before: ("artist_website", "http://dreamstatelogic.com")
2021/05/26 10:08:31 [crossfade:4] Before: ("replay_gain", "1.00")
2021/05/26 10:08:31 [crossfade:4] Before: ("tenc", "LAME in FL Studio 12")
2021/05/26 10:08:31 [crossfade:4] Before: ("label_links", "[]")
2021/05/26 10:08:31 [crossfade:4] Before: ("status", "playing")
2021/05/26 10:08:31 [crossfade:4] Before: ("encodedby", "LAME in FL Studio 12")
2021/05/26 10:08:31 [crossfade:4] Before: ("tpe2", "Dreamstate Logic")
2021/05/26 10:08:31 [crossfade:4] Before: ("tbpm", "90")
2021/05/26 10:08:31 [crossfade:4] Before: ("album_year", "2016")
2021/05/26 10:08:31 [crossfade:4] Before: ("label_website", "")
2021/05/26 10:08:31 [crossfade:4] Before: ("albumartist", "Dreamstate Logic")
2021/05/26 10:08:31 [crossfade:4] Before: ("on_air", "2021/05/26 09:58:41")
2021/05/26 10:08:31 [crossfade:4] Before: ("rid", "7")
2021/05/26 10:08:31 [crossfade:4] Before: ("genre", "Psybient / Downtempo")
2021/05/26 10:08:31 [crossfade:4] Before: ("album_artist", "Dreamstate Logic")
2021/05/26 10:08:31 [crossfade:4] After : ("album", "Infra Versus Ultra")
2021/05/26 10:08:31 [crossfade:4] After : ("author_links", "[\"https://neurotech.bandcamp.com\"]")
2021/05/26 10:08:31 [crossfade:4] After : ("track", "4")
2021/05/26 10:08:31 [crossfade:4] After : ("date", "2014")
2021/05/26 10:08:31 [crossfade:4] After : ("kind", "{audio=pcm(stereo),video=none,midi=none}")
2021/05/26 10:08:31 [crossfade:4] After : ("artist", "Neurotech")
2021/05/26 10:08:31 [crossfade:4] After : ("decoder", "FFMPEG")
2021/05/26 10:08:31 [crossfade:4] After : ("title", "Unleashing the Dead")
2021/05/26 10:08:31 [crossfade:4] After : ("label", "")
2021/05/26 10:08:31 [crossfade:4] After : ("filename", "/var/www/deer-radio.ru/storage/app/public/songs/February2019/TkmdxNeWcxgLCYJUKn9l.flac")
2021/05/26 10:08:31 [crossfade:4] After : ("id", "200")
2021/05/26 10:08:31 [crossfade:4] After : ("temporary", "false")
2021/05/26 10:08:31 [crossfade:4] After : ("source", "audio_stream")
2021/05/26 10:08:31 [crossfade:4] After : ("initial_uri", "annotate:id=\"200\",artist=\"Neurotech\",title=\"Unleashing the Dead\",replay_gain=\"1.00\",album=\"Infra Versus Ultra\",album_year=\"2014\",artist_website=\"https://neurotech.bandcamp.com\",author_links=\"[\\\"https:\\/\\/neurotech.bandcamp.com\\\"]\",label=\"\",label_website=\"\",label_links=\"[]\":file:///var/www/deer-radio.ru/storage/app/public/songs/February2019/TkmdxNeWcxgLCYJUKn9l.flac")
2021/05/26 10:08:31 [crossfade:4] After : ("unsyncedlyrics", "Inside a revolving door\r\nWe ride\r\nOur presences collide\r\nAre we just driven by faith\r\nOr blind chance\r\nUnpredictable, illogical\r\nAre we just filling the gap\r\nIn our empty lives\r\nTransitioning into unified\r\nBut in the mist of confusion\r\nI still wish you would\r\nHold me close\r\nWhen we are gone\r\nAnd there is nothing\r\nLeft to save\r\nUnleashing the dead\r\nWho believe in each other\r\nWhen we are gone\r\nAnd there is no more\r\nWords to say\r\nUnleashing the dead\r\nWho believe in one another\r\nAnd I believe in me right now\r\nInside an evolving core\r\nWe hide\r\nOur presences unite\r\nAre we just moving in waves,\r\nyet standstill\r\nIn the wrong place\r\nAgainst our own will\r\nAre we just spending\r\nThe years trying to forget\r\nThe true essence\r\nOf truth and lies\r\nThere is only\r\nPain in confusion\r\nI wish you would let me go\r\nHold\r\nLet me go")
2021/05/26 10:08:31 [crossfade:4] After : ("artist_website", "https://neurotech.bandcamp.com")
2021/05/26 10:08:31 [crossfade:4] After : ("replay_gain", "1.00")
2021/05/26 10:08:31 [crossfade:4] After : ("label_links", "[]")
2021/05/26 10:08:31 [crossfade:4] After : ("status", "playing")
2021/05/26 10:08:31 [crossfade:4] After : ("album_year", "2014")
2021/05/26 10:08:31 [crossfade:4] After : ("label_website", "")
2021/05/26 10:08:31 [crossfade:4] After : ("on_air", "2021/05/26 10:08:28")
2021/05/26 10:08:31 [crossfade:4] After : ("rid", "0")
2021/05/26 10:08:31 [crossfade:4] After : ("comment", "Visit http://neurotech.bandcamp.com")
2021/05/26 10:08:31 [crossfade:4] After : ("album_artist", "Neurotech")
2021/05/26 10:08:31 [crossfade:3] Simple transition: crossed, fade-in, fade-out.
2021/05/26 10:08:31 [source:4] Source add_35888 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [add_35888:5] Clock is cross_29193[].
2021/05/26 10:08:31 [add_35888:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [add_35888:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source fade.in gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [fade.in:5] Clock is cross_29193[].
2021/05/26 10:08:31 [fade.in:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [fade.in:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source fade.in gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [fade.in:5] Clock is cross_29193[].
2021/05/26 10:08:31 [fade.in:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [fade.in:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source cross_29193_after gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [cross_29193_after:5] Clock is cross_29193[].
2021/05/26 10:08:31 [cross_29193_after:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [cross_29193_after:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source buffer_35763 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [buffer_35763:5] Clock is cross_29193[].
2021/05/26 10:08:31 [buffer_35763:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [buffer_35763:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [buffer_35763:5] Activations changed: static=[cross_29193_after:fade.in:fade.in:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [cross_29193_after:5] Activations changed: static=[fade.in:fade.in:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [fade.in:5] Activations changed: static=[fade.in:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [fade.in:5] Activations changed: static=[add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [source:4] Source fade.out gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [fade.out:5] Clock is cross_29193[].
2021/05/26 10:08:31 [fade.out:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [fade.out:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source fade.out gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [fade.out:5] Clock is cross_29193[].
2021/05/26 10:08:31 [fade.out:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [fade.out:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source on_end_35883 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [on_end_35883:5] Clock is cross_29193[].
2021/05/26 10:08:31 [on_end_35883:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [on_end_35883:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source cross_29193_before gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [cross_29193_before:5] Clock is cross_29193[].
2021/05/26 10:08:31 [cross_29193_before:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [cross_29193_before:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [source:4] Source buffer_35761 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [buffer_35761:5] Clock is cross_29193[].
2021/05/26 10:08:31 [buffer_35761:5] Content kind: {audio=pcm(stereo),video=none,midi=none}, content type: {audio=pcm(stereo),video=none,midi=none}
2021/05/26 10:08:31 [buffer_35761:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2021/05/26 10:08:31 [buffer_35761:5] Activations changed: static=[cross_29193_before:on_end_35883:fade.out:fade.out:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [cross_29193_before:5] Activations changed: static=[on_end_35883:fade.out:fade.out:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [on_end_35883:5] Activations changed: static=[fade.out:fade.out:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [fade.out:5] Activations changed: static=[fade.out:add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [fade.out:5] Activations changed: static=[add_35888:cross_29193], dynamic=[].
2021/05/26 10:08:31 [add_35888:5] Activations changed: static=[], dynamic=[cross_29193].
2021/05/26 10:08:31 [add_35888:5] Activations changed: static=[], dynamic=[cross_29193, cross_29193].
2021/05/26 10:08:31 [buffer_35763:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [cross_29193_after:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [cross_29193_after:4] Fading in (type: sin, duration: 3.s).
2021/05/26 10:08:31 [fade.in:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [fade.in:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [cross_29193_before:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [on_end_35883:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [fade.out:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [fade.out:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [add_35888:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [cross_29193:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [sequence_29842:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [mksafe:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [mksafe:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:31 [clock.main:2] We must catchup 3.35 seconds!
2021/05/26 10:08:32 [mux_video_29613:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [clock.main:2] We must catchup 3.16 seconds!
2021/05/26 10:08:33 [cross_29193_before:4] Fading out with 3.s remaining.
2021/05/26 10:08:33 [deer_image:4] Finished with "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg".
2021/05/26 10:08:33 [deer_image:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2021/05/26 10:08:33 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg"...
2021/05/26 10:08:33 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg".
2021/05/26 10:08:33 [image.decoder:5] Decoding to 1920x1080 at 0x0
2021/05/26 10:08:33 [deer_image:3] Prepared "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg" (RID 6).
2021/05/26 10:08:33 [deer_image:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [lang:3] Got new deer image: Michael Scherback
2021/05/26 10:08:33 [video.add_text_29292:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29321:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29350:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29379:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29408:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29437:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29466:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29495:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29524:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29553:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:33 [video.add_text_29582:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:34 [clock.main:2] We must catchup 1.88 seconds!
2021/05/26 10:08:34 [mux_video_29613:5] Got metadata at position 0: calling handlers...
2021/05/26 10:08:34 [buffer_35763:4] Buffer emptied, buffering needed.
2021/05/26 10:08:34 [buffer_35761:4] Buffer emptied, buffering needed.
2021/05/26 10:08:34 [add_35888:5] Activations changed: static=[], dynamic=[cross_29193].
2021/05/26 10:08:34 [cross_29193:4] Buffering end of track...
2021/05/26 10:08:34 [cross_29193:4] More buffering will be needed.
2021/05/26 10:08:52 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:current
2021/05/26 10:08:52 [request:5] Resolving request [[annotate:image_link="https://unsplash.com/photos/IESdfa1E-ao",author="Annie Spratt",profile_link="https://unsplash.com/@anniespratt",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg]].
2021/05/26 10:08:52 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:08:52 [decoder.ffmpeg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:08:52 [decoder:4] Available decoders: IMAGE (priority: 1)
2021/05/26 10:08:52 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"...
2021/05/26 10:08:52 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg".
2021/05/26 10:08:52 [decoder:4] Selected decoder IMAGE for file "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/05/26 10:08:52 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:08:52 [decoder.taglib:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:08:52 [decoder.id3v2:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:08:52 [request:5] Resolved to [[/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg], [annotate:image_link="https://unsplash.com/photos/IESdfa1E-ao",author="Annie Spratt",profile_link="https://unsplash.com/@anniespratt",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg]].
2021/05/26 10:08:52 [deer_image:4] Remaining: 9.7s, queued: 0.0s, adding: 30.0s (RID 4)
2021/05/26 10:09:02 [deer_image:4] Finished with "/var/www/deer-radio.ru/storage/app/public/deer_image1622012852.jpg".
2021/05/26 10:09:02 [deer_image:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2021/05/26 10:09:02 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"...
2021/05/26 10:09:02 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg".
2021/05/26 10:09:02 [image.decoder:5] Decoding to 1920x1080 at 0x0
2021/05/26 10:09:02 [deer_image:3] Prepared "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg" (RID 4).
2021/05/26 10:09:02 [deer_image:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [lang:3] Got new deer image: Annie Spratt
2021/05/26 10:09:02 [video.add_text_29292:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29321:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29350:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29379:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29408:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29437:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29466:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29495:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29524:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29553:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:02 [video.add_text_29582:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:03 [mux_video_29613:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:22 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:current
2021/05/26 10:09:22 [request:5] Resolving request [[annotate:image_link="https://unsplash.com/photos/IESdfa1E-ao",author="Annie Spratt",profile_link="https://unsplash.com/@anniespratt",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg]].
2021/05/26 10:09:22 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:09:22 [decoder.ffmpeg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:09:22 [decoder:4] Available decoders: IMAGE (priority: 1)
2021/05/26 10:09:22 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"...
2021/05/26 10:09:23 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg".
2021/05/26 10:09:23 [decoder:4] Selected decoder IMAGE for file "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/05/26 10:09:23 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:09:23 [decoder.taglib:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:09:23 [decoder.id3v2:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"!
2021/05/26 10:09:23 [request:5] Resolved to [[/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg], [annotate:image_link="https://unsplash.com/photos/IESdfa1E-ao",author="Annie Spratt",profile_link="https://unsplash.com/@anniespratt",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg]].
2021/05/26 10:09:23 [deer_image:4] Remaining: 9.4s, queued: 0.0s, adding: 30.0s (RID 5)
2021/05/26 10:09:30 [lang:3] updating deer image
2021/05/26 10:09:30 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:update
2021/05/26 10:09:32 [deer_image:4] Finished with "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg".
2021/05/26 10:09:32 [deer_image:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2021/05/26 10:09:32 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg"...
2021/05/26 10:09:32 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg".
2021/05/26 10:09:32 [image.decoder:5] Decoding to 1920x1080 at 0x0
2021/05/26 10:09:32 [deer_image:3] Prepared "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg" (RID 5).
2021/05/26 10:09:32 [deer_image:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [lang:3] Got new deer image: Annie Spratt
2021/05/26 10:09:32 [video.add_text_29292:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29321:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29350:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29379:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29408:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29437:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29466:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29495:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29524:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29553:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:32 [video.add_text_29582:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:33 [mux_video_29613:5] Got metadata at position 0: calling handlers...
2021/05/26 10:09:52 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:current
2021/05/26 10:09:52 [request:5] Resolving request [[annotate:image_link="https://unsplash.com/photos/rVk-eKQeEO4",author="Manuel Keller",profile_link="https://unsplash.com/@emkaay",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg]].
2021/05/26 10:09:52 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:09:52 [decoder.ffmpeg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:09:52 [decoder:4] Available decoders: IMAGE (priority: 1)
2021/05/26 10:09:52 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"...
2021/05/26 10:09:52 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg".
2021/05/26 10:09:52 [decoder:4] Selected decoder IMAGE for file "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/05/26 10:09:52 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:09:52 [decoder.taglib:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:09:52 [decoder.id3v2:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:09:52 [request:5] Resolved to [[/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg], [annotate:image_link="https://unsplash.com/photos/rVk-eKQeEO4",author="Manuel Keller",profile_link="https://unsplash.com/@emkaay",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg]].
2021/05/26 10:09:52 [deer_image:4] Remaining: 9.6s, queued: 0.0s, adding: 30.0s (RID 1)
2021/05/26 10:10:02 [deer_image:4] Finished with "/var/www/deer-radio.ru/storage/app/public/deer_image1622012910.jpg".
2021/05/26 10:10:02 [deer_image:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2021/05/26 10:10:02 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"...
2021/05/26 10:10:02 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg".
2021/05/26 10:10:02 [image.decoder:5] Decoding to 1920x1080 at 0x0
2021/05/26 10:10:02 [deer_image:3] Prepared "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg" (RID 1).
2021/05/26 10:10:02 [deer_image:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [lang:3] Got new deer image: Manuel Keller
2021/05/26 10:10:02 [video.add_text_29292:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29321:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29350:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29379:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29408:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29437:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29466:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29495:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29524:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29553:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:02 [video.add_text_29582:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:03 [mux_video_29613:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:22 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:current
2021/05/26 10:10:22 [request:5] Resolving request [[annotate:image_link="https://unsplash.com/photos/rVk-eKQeEO4",author="Manuel Keller",profile_link="https://unsplash.com/@emkaay",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg]].
2021/05/26 10:10:22 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:10:22 [decoder.ffmpeg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:10:22 [decoder:4] Available decoders: IMAGE (priority: 1)
2021/05/26 10:10:22 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"...
2021/05/26 10:10:22 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg".
2021/05/26 10:10:22 [decoder:4] Selected decoder IMAGE for file "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg" with expected kind {audio=none,video=yuva420p,midi=none} and detected content {audio=none,video=yuva420p,midi=none}
2021/05/26 10:10:22 [decoder.ogg:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:10:22 [decoder.taglib:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:10:22 [decoder.id3v2:4] Invalid file extension for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"!
2021/05/26 10:10:22 [request:5] Resolved to [[/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg], [annotate:image_link="https://unsplash.com/photos/rVk-eKQeEO4",author="Manuel Keller",profile_link="https://unsplash.com/@emkaay",description="",duration="30":file:///var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg]].
2021/05/26 10:10:22 [deer_image:4] Remaining: 9.6s, queued: 0.0s, adding: 30.0s (RID 2)
2021/05/26 10:10:30 [lang:3] updating deer image
2021/05/26 10:10:30 [sandbox:5] Command: php /var/www/deer-radio.ru/artisan deer-image:update
2021/05/26 10:10:32 [deer_image:4] Finished with "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg".
2021/05/26 10:10:32 [deer_image:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2021/05/26 10:10:32 [decoder:4] Trying method "CAMLIMAGES" for "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg"...
2021/05/26 10:10:32 [decoder:3] Method "CAMLIMAGES" accepted "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg".
2021/05/26 10:10:32 [image.decoder:5] Decoding to 1920x1080 at 0x0
2021/05/26 10:10:32 [deer_image:3] Prepared "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg" (RID 2).
2021/05/26 10:10:32 [deer_image:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [lang:3] Got new deer image: Manuel Keller
2021/05/26 10:10:32 [video.add_text_29292:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29321:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29350:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29379:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29408:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29437:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29466:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29495:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29524:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29553:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:32 [video.add_text_29582:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:33 [mux_video_29613:5] Got metadata at position 0: calling handlers...
2021/05/26 10:10:42 [clock.main:4] Source output.url failed while streaming: FFmpeg.Avutils.Error(Broken pipe)!
Raised by primitive operation at file "encoder/ffmpeg_internal_encoder.ml", line 366, characters 8-35
Called from file "tools/ffmpeg_utils.ml", line 176, characters 14-45
Called from file "encoder/ffmpeg_internal_encoder.ml", line 391, characters 8-39
Called from file "option.ml", line 24, characters 57-62
Called from file "encoder/ffmpeg_encoder_common.ml", line 60, characters 4-80
Called from file "encoder/ffmpeg_encoder_common.ml", line 188, characters 4-44
Called from file "outputs/output.ml", line 260, characters 21-32
Called from file "outputs/output.ml", line 184, characters 45-61
Called from file "clock.ml", line 301, characters 14-22

2021/05/26 10:10:42 [output.url:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source output.url gets down.
2021/05/26 10:10:42 [mux_video_29613:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source mux_video_29613 gets down.
2021/05/26 10:10:42 [main:3] Shutdown started!
2021/05/26 10:10:42 [lifecycle:5] At stage: "Liquidsoap core shutdown"
2021/05/26 10:10:42 [main:3] Waiting for main threads to terminate...
2021/05/26 10:10:42 [threads:4] Waiting for thread clock_main to shutdown
2021/05/26 10:10:42 [video_aux_29615:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video_aux_29615 gets down.
2021/05/26 10:10:42 [video.add_text_29582:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29582 gets down.
2021/05/26 10:10:42 [video.add_text_29553:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29553 gets down.
2021/05/26 10:10:42 [video.add_text_29524:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29524 gets down.
2021/05/26 10:10:42 [video.add_text_29495:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29495 gets down.
2021/05/26 10:10:42 [video.add_text_29466:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29466 gets down.
2021/05/26 10:10:42 [video.add_text_29437:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29437 gets down.
2021/05/26 10:10:42 [video.add_text_29408:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29408 gets down.
2021/05/26 10:10:42 [video.add_text_29379:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29379 gets down.
2021/05/26 10:10:42 [video.add_text_29350:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29350 gets down.
2021/05/26 10:10:42 [video.add_text_29321:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29321 gets down.
2021/05/26 10:10:42 [video.add_text_29292:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source video.add_text_29292 gets down.
2021/05/26 10:10:42 [deer_image:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source deer_image gets down.
2021/05/26 10:10:42 [deer_image:4] Waiting for feeding task to stop...
2021/05/26 10:10:42 [deer_image:4] Finished with "/var/www/deer-radio.ru/storage/app/public/deer_image1622012972.jpg".
2021/05/26 10:10:42 [deer_image:4] Cleaning up request queue...
2021/05/26 10:10:42 [audio_main_29614:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source audio_main_29614 gets down.
2021/05/26 10:10:42 [mksafe:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source mksafe gets down.
2021/05/26 10:10:42 [mksafe:5] Activations changed: static=[mksafe:audio_main_29614:audio_main_29614], dynamic=[].
2021/05/26 10:10:42 [safe_blank:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source safe_blank gets down.
2021/05/26 10:10:42 [mksafe:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source mksafe gets down.
2021/05/26 10:10:42 [cross_29193:5] Activations changed: static=[sequence_29842:mksafe:mksafe:audio_main_29614:audio_main_29614], dynamic=[].
2021/05/26 10:10:42 [safe_blank:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source safe_blank gets down.
2021/05/26 10:10:42 [sequence_29842:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source sequence_29842 gets down.
2021/05/26 10:10:42 [max_duration_29841:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source max_duration_29841 gets down.
2021/05/26 10:10:42 [fail_29869:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source fail_29869 gets down.
2021/05/26 10:10:42 [cross_29193:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source cross_29193 gets down.
2021/05/26 10:10:42 [amplify_29164:5] Activations changed: static=[], dynamic=[cross_29193].
2021/05/26 10:10:42 [amplify_29164:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source amplify_29164 gets down.
2021/05/26 10:10:42 [blank.detect_29133:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source blank.detect_29133 gets down.
2021/05/26 10:10:42 [audio_stream:5] Activations changed: static=[], dynamic=[].
2021/05/26 10:10:42 [source:5] Source audio_stream gets down.
2021/05/26 10:10:42 [audio_stream:4] Waiting for feeding task to stop...
2021/05/26 10:10:42 [audio_stream:4] Finished with "/var/www/deer-radio.ru/storage/app/public/songs/February2019/TkmdxNeWcxgLCYJUKn9l.flac".
2021/05/26 10:10:42 [audio_stream:4] Cleaning up request queue...
2021/05/26 10:10:42 [clock.main:3] Streaming loop stopped.
2021/05/26 10:10:42 [threads:4] Thread "clock_main" terminated (0 remaining).
2021/05/26 10:10:42 [main:3] Main threads terminated.
2021/05/26 10:10:42 [lifecycle:5] At stage: "Liquidsoap scheduler shutdown"
2021/05/26 10:10:42 [threads:3] Shutting down scheduler...
2021/05/26 10:10:42 [threads:3] Scheduler shut down.
2021/05/26 10:10:42 [threads:3] Waiting for queue threads to terminate...
2021/05/26 10:10:42 [threads:4] Waiting for thread generic queue #1 to shutdown
2021/05/26 10:10:42 [threads:4] Thread "non-blocking queue #1" terminated (3 remaining).
2021/05/26 10:10:42 [threads:4] Thread "generic queue #1" terminated (2 remaining).
2021/05/26 10:10:42 [threads:4] Waiting for thread generic queue #2 to shutdown
2021/05/26 10:10:42 [threads:4] Thread "generic queue #2" terminated (1 remaining).
2021/05/26 10:10:42 [threads:4] Waiting for thread non-blocking queue #2 to shutdown
2021/05/26 10:10:42 [threads:4] Thread "non-blocking queue #2" terminated (0 remaining).
2021/05/26 10:10:42 [threads:3] Queues shut down
2021/05/26 10:10:42 [lifecycle:5] At stage: "Liquidsoap final cleanup"
2021/05/26 10:10:42 [main:3] Cleaning downloaded files...
2021/05/26 10:10:42 [main:3] Freeing memory...
2021/05/26 10:10:42 >>> LOG END
2021/05/26 10:10:44 >>> LOG START
2021/05/26 10:10:43 [main:3] Liquidsoap 2.0.0+git@0c9026d3
...

Version details

• OS: Ubuntu
• Version 20.04

Install method From source, main/master branches

[StarveTheEgo]

Another source of stopping stream, along with 'broken pipe', is 'connection reset by peer', if it matters; After this, the service automatically restarts, but no new stream is created on YouTube, but liquidsoap streams to nowhere

I can send you log file (lines after restart) via PM in Slack, if it matters. It just looks like a normal log of completely working liquidsoap to me; Songs are changing, images are changing, but no stream to YouTube at all;

Issue also could be with some changes on YouTube during 2020; Because i can not restart the service without opening 'YouTube Studio' webpage manually. If i do not have it opened - no stream is started automatically after restart. When i just open the studio, then restarting works. Quite weird and i am lost again in all of this, heh

[StarveTheEgo]

Issue is kind of solved, when i switched to default YouTube stream key in the dashboard.