search

Moonbase59 / autocue

On-the-fly JSON song cue-in, cue-out, overlay, replaygain calculation for Liquidsoap, AzuraCast and other AutoDJ software.
https://moonbase59.github.io/autocue/
MIT License
22 stars 3 forks source link

Azuracast specific - New songs don't get autocued. #33

Open gAlleb opened 1 week ago

gAlleb commented 1 week ago

So, when you add new songs to Azuracast playlist - the system works in a way, that usually it gets queued instantly and you can expect it to play within 4-5 tracks. However "new" song fails to be autocued.

To reproduce one can upload new tracks to azuracast media (I simply copy a folder to my NAS and then pick a folder in Azuracast interface and assign it to a playlist) and expect it to play within 4-5 tracks (2 are prefetched + 2 are in Azuraccast queue).

Logs:

Look for `Split Iris` song ``` 2024/07/08 08:52:11 [lang:3] API nextsong - Response (200): autocue:annotate:title="No Fear",artist="Split Iris",duration="238.18",song_id="50988e4291835d5b9239837659416ee7",media_id="17565",playlist_id="3":media:GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac 2024/07/08 08:52:11 [decoder.ffmpeg:3] Requested content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac": {audio=pcm(stereo)} 2024/07/08 08:52:11 [decoder.ffmpeg:3] FFmpeg recognizes "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" as audio: {codec: flac, 44100Hz, 2 channel(s)}, video: {codec: mjpeg, 800x800, yuvj420p} 2024/07/08 08:52:11 [decoder.ffmpeg:3] Decoded content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac": {audio=pcm(stereo)} 2024/07/08 08:52:11 [autocue.cue_file:3] Now autocueing: "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" 2024/07/08 08:52:11 [autocue.cue_file:3] Blank (silence) skipping active: false, set to 0. s 2024/07/08 08:52:11 [autocue.cue_file:3] Clipping prevention active: false 2024/07/08 08:52:11 [autocue.cue_file:3] Writing tags: false, including ReplayGain: false 2024/07/08 08:52:11 [autocue.cue_file:3] Writing metadata to /tmp/cue_file8a54da.json 2024/07/08 08:52:11 [cover:3] Cover for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Wolves At The Gate/Albums/2019 - Eclipse/12 - Counterfeit.mp3" saved to "/tmp/cover_78938a.jpeg". 2024/07/08 08:52:11 [track_video_add_image.656:3] Content type is {video=canvas}. 2024/07/08 08:52:11 [video.crop.335:3] Content type is {video=canvas}. 2024/07/08 08:52:11 [track_video_add_image.655:3] "/tmp/cover_78938a.jpeg" is static, resolving once for all... 2024/07/08 08:52:11 [decoder:3] Method "sdl" accepted "/tmp/cover_78938a.jpeg". 2024/07/08 08:52:11 [decoder:3] Method "sdl" accepted "/tmp/cover_78938a.jpeg". 2024/07/08 08:52:11 [track_video_add_image.655:3] Prepared "/tmp/cover_78938a.jpeg" (RID 991). 2024/07/08 08:52:12 [nowplaying:3] API playlist/3 - Playlist "Rock", requests: true, scheduled: false 2024/07/08 08:52:12 [nowplaying:3] Show: "Playlist: Rock", color=0x555555, alpha=0.6 2024/07/08 08:52:12 [lang:3] API feedback - Sending POST request to 'http://127.0.0.1:6010/api/internal/1/liquidsoap/feedback' with body: { 2024/07/08 08:52:12 [lang:3] "song_id": "481c81d184b3fbec5ca510c735915022", 2024/07/08 08:52:12 [lang:3] "playlist_id": "12", 2024/07/08 08:52:12 [lang:3] "media_id": "12864" 2024/07/08 08:52:12 [lang:3] } 2024/07/08 08:52:12 [lang:3] API feedback - Response (200): true 2024/07/08 08:52:15 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" 2024/07/08 08:52:15 [autocue.metadata:2] No autocue data found for file annotate:title="No Fear",artist="Split Iris",duration="238.18",song_id="50988e4291835d5b9239837659416ee7",media_id="17565",playlist_id="3":media:GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac 2024/07/08 08:52:15 [autocue.protocol:2] No autocue data found for URI annotate:title="No Fear",artist="Split Iris",duration="238.18",song_id="50988e4291835d5b9239837659416ee7",media_id="17565",playlist_id="3":media:GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac! 2024/07/08 08:52:15 [decoder.ffmpeg:3] Requested content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac": {audio=pcm(stereo)} 2024/07/08 08:52:15 [decoder.ffmpeg:3] FFmpeg recognizes "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" as audio: {codec: flac, 44100Hz, 2 channel(s)}, video: {codec: mjpeg, 800x800, yuvj420p} 2024/07/08 08:52:15 [decoder.ffmpeg:3] Decoded content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac": {audio=pcm(stereo)} 2024/07/08 08:52:18 [next_song:3] Prepared "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Melt-Banana/13 Hedgehogs (MxBx Singles 199/48 Least Clipper.mp3" (RID 986). [mp3float @ 0x7fca28065400] Could not update timestamps for skipped samples. 2024/07/08 08:52:18 [lang:3] API nextsong - Sending POST request to 'http://127.0.0.1:6010/api/internal/1/liquidsoap/nextsong' with body: 2024/07/08 08:52:18 [show_meta:3] ("duration", "107.00") 2024/07/08 08:52:18 [show_meta:3] Now playing: Melt-Banana - Least Clipper 2024/07/08 08:52:18 [show_meta:2] Warning: No liq_amplify found, expect loudness jumps! 2024/07/08 08:52:18 [cross:3] Analysis: -21.522173dB / -7.487010dB (2.46s / 2.46s) 2024/07/08 08:52:18 [cross:3] Computing crossfade transition over overlapping 2.46s buffered data at start and end. 2024/07/08 08:52:18 [source.854:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [audio.add.279:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [fade_in.279:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [fade_scale.279:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [track_amplify.559:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [blank.284:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [on_metadata.558:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [on_track.558:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [cross_after.279:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [buffer.558:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [fade_out.279:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [track_amplify.560:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [on_frame.561:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [on_metadata.559:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [on_track.559:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [cross_before.279:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:18 [buffer.557:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:52:19 [cover:3] File "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Melt-Banana/13 Hedgehogs (MxBx Singles 199/48 Least Clipper.mp3" has no cover. 2024/07/08 08:52:19 [lang:3] API nextsong - Response (200): autocue:annotate:title="Doomed",artist="Bring Me The Horizon",duration="274.00",song_id="b7087f20e2e7ab58439e61005fbaa37d",media_id="10071",playlist_id="3":media:GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3 2024/07/08 08:52:19 [track_video_add_image.658:3] Content type is {video=canvas}. 2024/07/08 08:52:19 [video.crop.336:3] Content type is {video=canvas}. 2024/07/08 08:52:19 [track_video_add_image.657:3] "/var/azuracast/stations/radio/media/youtube-stream/img/cover_default.jpg" is static, resolving once for all... 2024/07/08 08:52:19 [decoder:3] Method "sdl" accepted "/var/azuracast/stations/radio/media/youtube-stream/img/cover_default.jpg". 2024/07/08 08:52:19 [decoder:3] Method "sdl" accepted "/var/azuracast/stations/radio/media/youtube-stream/img/cover_default.jpg". 2024/07/08 08:52:19 [track_video_add_image.657:3] Prepared "/var/azuracast/stations/radio/media/youtube-stream/img/cover_default.jpg" (RID 992). [swscaler @ 0x7fca24c55300] Warning: data is not aligned! This can lead to a speed loss 2024/07/08 08:52:19 [decoder.ffmpeg:3] Requested content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3": {audio=pcm(stereo)} 2024/07/08 08:52:19 [decoder.ffmpeg:3] FFmpeg recognizes "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3" as audio: {codec: mp3, 44100Hz, 2 channel(s)}, video: {codec: mjpeg, 500x500, yuvj422p} 2024/07/08 08:52:19 [decoder.ffmpeg:3] Decoded content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3": {audio=pcm(stereo)} 2024/07/08 08:52:19 [autocue.cue_file:3] Now autocueing: "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3" 2024/07/08 08:52:19 [autocue.cue_file:3] Blank (silence) skipping active: false, set to 0. s 2024/07/08 08:52:19 [autocue.cue_file:3] Clipping prevention active: false 2024/07/08 08:52:19 [autocue.cue_file:3] Writing tags: false, including ReplayGain: false 2024/07/08 08:52:19 [autocue.cue_file:3] Writing metadata to /tmp/cue_file0a3d3a.json 2024/07/08 08:52:19 [nowplaying:3] API playlist/3 - Playlist "Rock", requests: true, scheduled: false 2024/07/08 08:52:19 [nowplaying:3] Show: "Playlist: Rock", color=0x555555, alpha=0.6 2024/07/08 08:52:19 [lang:3] API feedback - Sending POST request to 'http://127.0.0.1:6010/api/internal/1/liquidsoap/feedback' with body: { 2024/07/08 08:52:19 [lang:3] "song_id": "137635eb89daf768dea89859c3025ab9", 2024/07/08 08:52:19 [lang:3] "playlist_id": "3", 2024/07/08 08:52:19 [lang:3] "media_id": "4839" 2024/07/08 08:52:19 [lang:3] } 2024/07/08 08:52:19 [lang:3] API feedback - Response (200): true 2024/07/08 08:52:23 [autocue.cue_file:3] cue_file result for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3": {"duration": 274.1, "liq_cue_duration": 269.9, "liq_cue_in": 1.6, "liq_cue_out": 271.5, "liq_cross_start_next": 270.4, "liq_longtail": false, "liq_sustained_ending": false, "liq_loudness": "-6.05 LUFS", "liq_loudness_range": "7.99 LU", "liq_amplify": "-11.95 dB", "liq_amplify_adjustment": "0.00 dB", "liq_reference_loudness": "-18.00 LUFS", "liq_blankskip": 0.0, "liq_blank_skipped": false, "liq_true_peak": 1.052, "liq_true_peak_db": "0.44 dBFS"} 2024/07/08 08:52:23 [autocue.cue_file:3] No fade-in duration given, using default setting (0.1 s). 2024/07/08 08:52:23 [autocue.cue_file:3] No fade-out duration given, using default setting (2.5 s). 2024/07/08 08:52:23 [autocue.cue_file:2] Given fade-out duration (2.5 s) exceeds available time, using 1.1 s. 2024/07/08 08:52:23 [autocue.cue_file:3] Metadata added/corrected for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3": 2024/07/08 08:52:23 [autocue.cue_file:3] ("duration", "274.00") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_amplify", "-11.95 dB") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_amplify_adjustment", "0.00 dB") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_blank_skipped", "false") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_blankskip", "0.") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_cross_start_next", "270.4") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_cue_duration", "269.90") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_cue_in", "1.6") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_cue_out", "271.5") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_fade_in", "0.1") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_fade_out", "1.1") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_longtail", "false") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_loudness", "-6.05 LUFS") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_loudness_range", "7.99 LU") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_reference_loudness", "-18.00 LUFS") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_sustained_ending", "false") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_true_peak", "1.052") 2024/07/08 08:52:23 [autocue.cue_file:3] ("liq_true_peak_db", "0.44 dBFS") 2024/07/08 08:52:23 [decoder.ffmpeg:3] Requested content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3": {audio=pcm(stereo)} 2024/07/08 08:52:23 [decoder.ffmpeg:3] FFmpeg recognizes "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3" as audio: {codec: mp3, 44100Hz, 2 channel(s)}, video: {codec: mjpeg, 500x500, yuvj422p} 2024/07/08 08:52:23 [decoder.ffmpeg:3] Decoded content-type for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3": {audio=pcm(stereo)} [mp3float @ 0x7fca28065400] Could not update timestamps for discarded samples. 2024/07/08 08:54:03 [decoder:2] Decoding "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Melt-Banana/13 Hedgehogs (MxBx Singles 199/48 Least Clipper.mp3" ended: Ffmpeg_decoder.End_of_file. 2024/07/08 08:54:03 [next_song:3] Prepared "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" (RID 989). 2024/07/08 08:54:03 [show_meta:3] ("duration", "238.18") 2024/07/08 08:54:03 [show_meta:3] Now playing: Split Iris - No Fear 2024/07/08 08:54:03 [show_meta:2] Warning: No liq_amplify found, expect loudness jumps! 2024/07/08 08:54:03 [lang:3] API nextsong - Sending POST request to 'http://127.0.0.1:6010/api/internal/1/liquidsoap/nextsong' with body: 2024/07/08 08:54:04 [cross:3] Analysis: -28.525398dB / -4.373979dB (2.51s / 2.53s) 2024/07/08 08:54:04 [cross:3] Computing crossfade transition over overlapping 2.51s buffered data at start and end. 2024/07/08 08:54:04 [source.857:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [audio.add.280:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [fade_in.280:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [fade_scale.280:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [track_amplify.561:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [blank.285:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [on_metadata.560:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [on_track.560:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [cross_after.280:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [buffer.561:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [fade_out.280:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [track_amplify.562:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [on_frame.563:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [on_metadata.561:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [on_track.561:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [cross_before.280:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [buffer.559:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [cross_after_tail:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:54:04 [cover:3] Cover for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" saved to "/tmp/cover_04e7c9.jpeg". 2024/07/08 08:54:04 [track_video_add_image.660:3] Content type is {video=canvas}. 2024/07/08 08:54:04 [video.crop.337:3] Content type is {video=canvas}. 2024/07/08 08:54:04 [track_video_add_image.659:3] "/tmp/cover_04e7c9.jpeg" is static, resolving once for all... 2024/07/08 08:54:04 [decoder:3] Method "sdl" accepted "/tmp/cover_04e7c9.jpeg". 2024/07/08 08:54:04 [decoder:3] Method "sdl" accepted "/tmp/cover_04e7c9.jpeg". 2024/07/08 08:54:04 [track_video_add_image.659:3] Prepared "/tmp/cover_04e7c9.jpeg" (RID 995). 2024/07/08 08:54:04 [lang:3] API nextsong - Response (200): autocue:annotate:title="Rock @ omFM",artist="Rock @ omFM",duration="13.61",song_id="481c81d184b3fbec5ca510c735915022",media_id="12859",playlist_id="12",jingle_mode="true":media:music_local/jingles/rock/day/audio-editor-output.mp3 2024/07/08 08:54:04 [decoder.ffmpeg:3] Requested content-type for "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3": {audio=pcm(stereo)} 2024/07/08 08:54:04 [decoder.ffmpeg:3] FFmpeg recognizes "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3" as audio: {codec: mp3, 44100Hz, 2 channel(s)} 2024/07/08 08:54:04 [decoder.ffmpeg:3] Decoded content-type for "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3": {audio=pcm(stereo)} [swscaler @ 0x7fca256b0c00] Warning: dstStride is not aligned! ->cannot do aligned memory accesses anymore 2024/07/08 08:54:04 [autocue.cue_file:3] Now autocueing: "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3" 2024/07/08 08:54:04 [autocue.cue_file:3] Blank (silence) skipping active: false, set to 0. s 2024/07/08 08:54:04 [autocue.cue_file:3] Clipping prevention active: false 2024/07/08 08:54:04 [autocue.cue_file:3] Writing tags: false, including ReplayGain: false 2024/07/08 08:54:04 [autocue.cue_file:3] Writing metadata to /tmp/cue_filee54106.json 2024/07/08 08:54:04 [nowplaying:3] API playlist/3 - Playlist "Rock", requests: true, scheduled: false 2024/07/08 08:54:04 [nowplaying:3] Show: "Playlist: Rock", color=0x555555, alpha=0.6 2024/07/08 08:54:04 [lang:3] API feedback - Sending POST request to 'http://127.0.0.1:6010/api/internal/1/liquidsoap/feedback' with body: { 2024/07/08 08:54:04 [lang:3] "song_id": "50988e4291835d5b9239837659416ee7", 2024/07/08 08:54:04 [lang:3] "playlist_id": "3", 2024/07/08 08:54:04 [lang:3] "media_id": "17565" 2024/07/08 08:54:04 [lang:3] } 2024/07/08 08:54:04 [autocue.cue_file:3] cue_file result for "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3": {"duration": 13.635918, "liq_cue_duration": 12.4, "liq_cue_in": 0.0, "liq_cue_out": 12.4, "liq_cross_start_next": 12.2, "liq_longtail": false, "liq_sustained_ending": true, "liq_loudness": "-9.04 LUFS", "liq_loudness_range": "4.93 LU", "liq_amplify": "-8.96 dB", "liq_amplify_adjustment": "0.00 dB", "liq_reference_loudness": "-18.00 LUFS", "liq_blankskip": 0.0, "liq_blank_skipped": false, "liq_true_peak": 1.108, "liq_true_peak_db": "0.89 dBFS"} 2024/07/08 08:54:04 [autocue.cue_file:3] No fade-in duration given, using default setting (0.1 s). 2024/07/08 08:54:04 [autocue.cue_file:3] No fade-out duration given, using default setting (2.5 s). 2024/07/08 08:54:04 [autocue.cue_file:2] Given fade-out duration (2.5 s) exceeds available time, using 0.2 s. 2024/07/08 08:54:04 [autocue.cue_file:3] Metadata added/corrected for "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3": 2024/07/08 08:54:04 [autocue.cue_file:3] ("duration", "13.61") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_amplify", "-8.96 dB") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_amplify_adjustment", "0.00 dB") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_blank_skipped", "false") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_blankskip", "0.00") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_cross_start_next", "12.2") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_cue_duration", "12.40") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_cue_in", "0.00") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_cue_out", "12.4") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_fade_in", "0.1") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_fade_out", "0.2") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_longtail", "false") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_loudness", "-9.04 LUFS") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_loudness_range", "4.93 LU") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_reference_loudness", "-18.00 LUFS") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_sustained_ending", "true") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_true_peak", "1.108000") 2024/07/08 08:54:04 [autocue.cue_file:3] ("liq_true_peak_db", "0.89 dBFS") 2024/07/08 08:54:04 [autocue.cue_file:3] ("replaygain_reference_loudness", "-18.00 LUFS") 2024/07/08 08:54:04 [autocue.cue_file:3] ("replaygain_track_gain", "-8.96 dB") 2024/07/08 08:54:04 [decoder.ffmpeg:3] Requested content-type for "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3": {audio=pcm(stereo)} 2024/07/08 08:54:04 [decoder.ffmpeg:3] FFmpeg recognizes "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3" as audio: {codec: mp3, 44100Hz, 2 channel(s)} 2024/07/08 08:54:04 [decoder.ffmpeg:3] Decoded content-type for "/var/azuracast/stations/radio/media/music_local/jingles/rock/day/audio-editor-output.mp3": {audio=pcm(stereo)} 2024/07/08 08:54:04 [lang:3] API feedback - Response (200): true 2024/07/08 08:57:59 [decoder:2] Decoding "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac" ended: Ffmpeg_decoder.End_of_file. 2024/07/08 08:57:59 [next_song:3] Prepared "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3" (RID 993). 2024/07/08 08:57:59 [lang:3] API nextsong - Sending POST request to 'http://127.0.0.1:6010/api/internal/1/liquidsoap/nextsong' with body: 2024/07/08 08:57:59 [show_meta:3] ("duration", "274.00") 2024/07/08 08:57:59 [show_meta:3] ("liq_amplify", "-11.95 dB") 2024/07/08 08:57:59 [show_meta:3] ("liq_amplify_adjustment", "0.00 dB") 2024/07/08 08:57:59 [show_meta:3] ("liq_autocue", "cue_file") 2024/07/08 08:57:59 [show_meta:3] ("liq_blank_skipped", "false") 2024/07/08 08:57:59 [show_meta:3] ("liq_blankskip", "0.") 2024/07/08 08:57:59 [show_meta:3] ("liq_cross_duration", "2.5") 2024/07/08 08:57:59 [show_meta:3] ("liq_cross_start_next", "270.4") 2024/07/08 08:57:59 [show_meta:3] ("liq_cue_duration", "269.90") 2024/07/08 08:57:59 [show_meta:3] ("liq_cue_in", "1.6") 2024/07/08 08:57:59 [show_meta:3] ("liq_cue_out", "271.5") 2024/07/08 08:57:59 [show_meta:3] ("liq_fade_in", "0.1") 2024/07/08 08:57:59 [show_meta:3] ("liq_fade_out", "1.1") 2024/07/08 08:57:59 [show_meta:3] ("liq_fade_out_delay", "1.4") 2024/07/08 08:57:59 [show_meta:3] ("liq_longtail", "false") 2024/07/08 08:57:59 [show_meta:3] ("liq_loudness", "-6.05 LUFS") 2024/07/08 08:57:59 [show_meta:3] ("liq_loudness_range", "7.99 LU") 2024/07/08 08:57:59 [show_meta:3] ("liq_reference_loudness", "-18.00 LUFS") 2024/07/08 08:57:59 [show_meta:3] ("liq_sustained_ending", "false") 2024/07/08 08:57:59 [show_meta:3] ("liq_true_peak", "1.052") 2024/07/08 08:57:59 [show_meta:3] ("liq_true_peak_db", "0.44 dBFS") 2024/07/08 08:57:59 [show_meta:3] Now playing: Bring Me The Horizon - Doomed 2024/07/08 08:57:59 [cross:3] Analysis: -23.142990dB / -38.396000dB (2.50s / 2.50s) 2024/07/08 08:57:59 [cross:3] Computing crossfade transition over reconciled duration with 2.50s ending and 2.50s starting buffered data. 2024/07/08 08:57:59 [source.860:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [audio.add.281:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [fade_in.281:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [fade_scale.281:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [track_amplify.563:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [blank.286:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [on_metadata.562:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [on_track.562:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [cross_after.281:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [buffer.563:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [fade_out.281:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [track_amplify.564:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [on_frame.565:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [on_metadata.563:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [on_track.563:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [cross_before.281:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [buffer.562:3] Content type is {audio=pcm(stereo)}. 2024/07/08 08:57:59 [cover:3] Cover for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Bring Me The Horizon/Albums (CD)/2015 - That's The Spirit (Japanese Edition) - (320 kbps)/01. Doomed.mp3" saved to "/tmp/cover_324a79.jpeg". 2024/07/08 08:57:59 [track_video_add_image.662:3] Content type is {video=canvas}. 2024/07/08 08:57:59 [video.crop.338:3] Content type is {video=canvas}. 2024/07/08 08:57:59 [track_video_add_image.661:3] "/tmp/cover_324a79.jpeg" is static, resolving once for all... 2024/07/08 08:57:59 [decoder:3] Method "sdl" accepted "/tmp/cover_324a79.jpeg". 2024/07/08 08:57:59 [lang:3] API nextsong - Response (200): autocue:annotate:title="I Could Never Fall In Love With You",artist="Tub Ring",duration="109.00",song_id="38f554772a4b5c4726437b669529d08e",media_id="4623",playlist_id="3":media:GoogleDrive_music/rock/Tub Ring/Zoo Hypothesis/1-05 I Could Never Fall In Love With You.mp3 2024/07/08 08:57:59 [decoder:3] Method "sdl" accepted "/tmp/cover_324a79.jpeg". 2024/07/08 08:57:59 [track_video_add_image.661:3] Prepared "/tmp/cover_324a79.jpeg" (RID 998). ```
Moonbase59 commented 1 week ago

Hm. It says

2024/07/08 08:52:15 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac"

so it hasn’t seen a result from cue_file, but also no error, or it would have logged cue_file error: xxx. Here: https://github.com/Moonbase59/autocue/blob/a2bcef1b1d88563d855aba8df805783835856b5d/autocue.cue_file.liq#L550-L581

  1. Are you on AzuraCast? If yes, which Rolling Release commit?
  2. Which version of Autocue and cue_file?
  3. Can you try to use cue_file on the command line from that host, for that file? What’s the result?
  4. What’s the result of 
    file "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac"
  5. What’s the result of 
    ffprobe "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac"
gAlleb commented 1 week ago
  1. Oui since the thread is Azuracast specific - 944fa6db1160ad1cee517f601c53895407b3947b
  2. 4.0.5
  3. Overlay: -13.97 LUFS, Longtail: -28.97 LUFS, Measured end avg: -42.41 LUFS, Drop: 41.79%
Overlay times: 233.40/0.00/0.00 s (normal/sustained/longtail), using: 233.40 s.
Cue out time: 236.00 s
{"duration": 238.1, "liq_cue_duration": 236.0, "liq_cue_in": 0.0, "liq_cue_out": 236.0, "liq_cross_start_next": 233.4, "liq_longtail": false, "liq_sustained_ending": false, "liq_loudness": "-5.97 LUFS", "liq_loudness_range": "1.47 LU", "liq_amplify": "-12.03 dB", "liq_amplify_adjustment": "0.00 dB", "liq_reference_loudness": "-18.00 LUFS", "liq_blankskip": 0.0, "liq_blank_skipped": false, "liq_true_peak": 1.016, "liq_true_peak_db": "0.14 dBFS"}
  4. file "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac"
/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac: FLAC audio bitstream data, 16 bit, stereo, 44.1 kHz, 10503658 samples
  5. 
ffprobe version 6.1.1 Copyright (c) 2007-2023 the FFmpeg developers
built with Apple clang version 15.0.0 (clang-1500.1.0.2.5)
configuration: --prefix='/usr/local/Cellar/ffmpeg@6/6.1.1_1' --enable-shared --enable-pthreads --enable-version3 --cc=clang --host-cflags= --host-ldflags='-Wl,-ld_classic' --enable-ffplay --enable-gnutls --enable-gpl --enable-libaom --enable-libaribb24 --enable-libbluray --enable-libdav1d --enable-libharfbuzz --enable-libjxl --enable-libmp3lame --enable-libopus --enable-librav1e --enable-librist --enable-librubberband --enable-libsnappy --enable-libsrt --enable-libssh --enable-libsvtav1 --enable-libtesseract --enable-libtheora --enable-libvidstab --enable-libvmaf --enable-libvorbis --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libxvid --enable-lzma --enable-libfontconfig --enable-libfreetype --enable-frei0r --enable-libass --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopenvino --enable-libspeex --enable-libsoxr --enable-libzmq --enable-libzimg --disable-libjack --disable-indev=jack --enable-videotoolbox --enable-audiotoolbox
libavutil      58. 29.100 / 58. 29.100
libavcodec     60. 31.102 / 60. 31.102
libavformat    60. 16.100 / 60. 16.100
libavdevice    60.  3.100 / 60.  3.100
libavfilter     9. 12.100 /  9. 12.100
libswscale      7.  5.100 /  7.  5.100
libswresample   4. 12.100 /  4. 12.100
libpostproc    57.  3.100 / 57.  3.100
Input #0, flac, from '/Users/Google Диск/_CLOUD/Music/music/rock/Split Iris/2024 - Bloodred Dusk/03 - No Fear.flac':
Metadata:
TITLE           : No Fear
ARTIST          : Split Iris
ALBUM           : Bloodred Dusk
album_artist    : Split Iris
track           : 3
disc            : 1
GENRE           : Metal
DATE            : 2024-06-28
LENGTH          : 238000
PUBLISHER       : Seek & Strike
ISRC            : QM6MZ2471953
BARCODE         : 198391891106
Duration: 00:03:58.18, start: 0.000000, bitrate: 1065 kb/s
Stream #0:0: Audio: flac, 44100 Hz, stereo, s16
Stream #0:1: Video: mjpeg (Progressive), yuvj420p(pc, bt470bg/unknown/unknown), 800x800 [SAR 300:300 DAR 1:1], 90k tbr, 90k tbn (attached pic)
Metadata:
  comment         : Cover (front)
Moonbase59 commented 1 week ago

Odd. No idea, tbh. I tried to reproduce it, albeit using Rolling Release #c982024 (2024-07-08 2:21) and Autocue 4.1.0. My files are mounted in the host as NFS mounts, and mounted into AzuraCast’s media folder using docker-compose.override.yaml. They appear local to AzuraCast.

I created a folder playlist with one file. That played of and over again, as expected, I then dropped two other files in (using the file manager). It took about 5–6 minutes until AzuraCast realized there were new files and added them to the playlist.

They were then queued in, autocued normally and played.

So I really don’t know what’s happening there. A situation where Autocue (LS) gets an empty result without also an error happening (and logged) should normally never happen, and your data looks good to me. Maybe some issue with AzuraCast or Google Drive, can’t really say.

gAlleb commented 1 week ago

My files are mounted in the host as NFS mounts, and mounted into AzuraCast’s media folder using docker-compose.override.yaml. They appear local to AzuraCast.

Please note that no GoogleDrive is involved into this. This is just a folder name.

Happened alongside that other track (Split Iris) around the same time:

2024/07/08 08:49:20 [decoder:3] Method "sdl" accepted "/tmp/cover_114574.jpeg".
2024/07/08 08:49:20 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/Melt-Banana/13 Hedgehogs (MxBx Singles 199/48 Least Clipper.mp3"
2024/07/08 08:49:20 [autocue.metadata:2] No autocue data found for file annotate:title="Least Clipper",artist="Melt-Banana",duration="107.00",song_id="137635eb89daf768dea89859c3025ab9",media_id="4839",playlist_id="3":media:GoogleDrive_music/rock/Melt-Banana/13 Hedgehogs (MxBx Singles 199/48 Least Clipper.mp3
2024/07/08 08:49:20 [autocue.protocol:2] No autocue data found for URI annotate:title="Least Clipper",artist="Melt-Banana",duration="107.00",song_id="137635eb89daf768dea89859c3025ab9",media_id="4839",playlist_id="3":media:GoogleDrive_music/rock/Melt-Banana/13 Hedgehogs (MxBx Singles 199/48 Least Clipper.mp3!
2024/07/08 08:49:20 [decoder:3] Method "sdl" accepted "/tmp/cover_114574.jpeg".
gAlleb commented 1 week ago

So for the past 12 hours there has been 3 similar cases:

2024/07/09 07:56:39 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/My Own Private Alaska/My Own Private Alaska/1-01 Die for me (If I say please).mp3"
2024/07/09 07:56:39 [autocue.metadata:2] No autocue data found for file annotate:title="Die For Me (If I Say Please)",artist="My Own Private Alaska",duration="486.00",song_id="bc2e227e7a91523d0e4d04bf6b41f077",media_id="5910",playlist_id="3":media:GoogleDrive_music/rock/My Own Private Alaska/My Own Private Alaska/1-01 Die for me (If I say please).mp3
2024/07/09 07:56:39 [autocue.protocol:2] No autocue data found for URI annotate:title="Die For Me (If I Say Please)",artist="My Own Private Alaska",duration="486.00",song_id="bc2e227e7a91523d0e4d04bf6b41f077",media_id="5910",playlist_id="3":media:GoogleDrive_music/rock/My Own Private Alaska/My Own Private Alaska/1-01 Die for me (If I say please).mp3!

So it just happens sometimes on random tracks... No errors, nothing, just the fact

2024/07/09 07:59:47 [next_song:3] Prepared "/var/azuracast/stations/radio/media/GoogleDrive_music/rock/My Own Private Alaska/My Own Private Alaska/1-01 Die for me (If I say please).mp3" (RID 845).
[mp3float @ 0x7fc6df7e1000] Could not update timestamps for skipped samples.
2024/07/09 07:59:47 [show_meta:3] ("duration", "486.00")
2024/07/09 07:59:47 [show_meta:3] ("media_id", "5910")
2024/07/09 07:59:47 [show_meta:3] Now playing: My Own Private Alaska - Die For Me (If I Say Please)
2024/07/09 07:59:47 [show_meta:2] Warning: No liq_amplify found, expect loudness jumps!

Not sure, maybe it has started when I switched back videostreaming. Maybe you have No autocue data found somewhere too and it's connected to Average load. However Average Load is ok, Rolling Release #60088c6, Autocue 4.1.0.

Moonbase59 commented 1 week ago

Indeed when grepping my logs over the past 5 days I find 8 occurrences:

$ grep -i '\[autocue.cue_file:2\] No autocue' /var/azuracast/stations/niteradio/config/liquidsoap.log*
/var/azuracast/stations/niteradio/config/liquidsoap.log:2024/07/09 04:00:46 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Coma/Coma - [untitled] (2011 album, PL)/Coma - Woda leży pod powierzchnią.mp3"
/var/azuracast/stations/niteradio/config/liquidsoap.log:2024/07/09 04:37:21 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Lost Weekend/Lost Weekend - Forever Moving On (2006 album, GB)/Lost Weekend - Save the Best 'til Last.mp3"
/var/azuracast/stations/niteradio/config/liquidsoap.log.1:2024/07/08 00:00:17 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/time/time.de-DE.mp3"
/var/azuracast/stations/niteradio/config/liquidsoap.log.2:2024/07/07 06:18:09 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Marx, Richard/Marx, Richard - Repeat Offender (1989 album, JP)/Marx, Richard - Too Late to Say Goodbye.mp3"
/var/azuracast/stations/niteradio/config/liquidsoap.log.2:2024/07/07 08:24:36 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Rosenberg, Marianne/Rosenberg, Marianne - Single Collection 1970-2011 (2012 album, compilation, DE)/Rosenberg, Marianne - Ruf' an!.flac"
/var/azuracast/stations/niteradio/config/liquidsoap.log.2:2024/07/07 21:30:14 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Smooth, Joe/Smooth, Joe - [compilations]/Smooth, Joe - Promised Land (Groove Armada remix).mp3"
/var/azuracast/stations/niteradio/config/liquidsoap.log.4:2024/07/06 02:39:28 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Blackfoot/Blackfoot - Marauder/Blackfoot - Fire Of the Dragon.mp3"
/var/azuracast/stations/niteradio/config/liquidsoap.log.5:2024/07/05 07:41:25 [autocue.cue_file:2] No autocue data found for "/var/azuracast/stations/niteradio/media/Tagged/Pet Shop Boys/Pet Shop Boys - PopArt_ The Hits/Pet Shop Boys - Always on My Mind.mp3"

I checked all these files for permissions, wrong format, defects or other issues, and they’re all okay.

Can’t find a reason why that should happen, and can see no systematology. Especially the time.de-DE.mp3 file is odd—that gets recreated every minute, and played at least once every hour.

We also typically get only a minimal set of metadata in the following [show_meta:3] log when this occurs.

I somehow suspect Liquidsoap isn’t passing me the metadata in the first place, but I’m unsure how to diagnose this. I’ll see if we can add some extra diagnostic code.

What you could do is set the log level to 4 (first input box):

settings.log.level := 4

and check the output of

[autocue.cue_file:4] Metadata seen for "filename":

for each such file. This shows exactly what we get from Liquidsoap (the file and request metadata combined) before invoking Autocue.

Hint: Logs are limited in size, for manageable web viewing, so it’s easier to hop into the Docker, cd into the station’s config folder /var/azuracast/stations/STATION/config/ and do a grep over the liquidsoap.log* filles.

Friendly reminder: "Latest release" isn’t meaningful, it can sometimes change within hours or even minutes. ;-)

Please use the 7-digit abbreviated commit hash shown on every AzuraCast page and on GitHub’s commits list:

Auswahl_470

Screenshot 2024-07-09 a