[clock.main:4] Source playlist failed while streaming: Pcre.Error(_)!

[ChoiZ]

Hello,

I'm trying to move from 1.4.4 to 2.0.4

And since 2h my playlist is working well and now boom.

Liquidsoap has crashed with this error.

[clock.main:4] Source playlist failed while streaming: Pcre.Error(_)!
[clock.main:4] Raised by primitive operation at Pcre.regexp in file "src/pcre.ml", line 275, characters 11-38

Anyone has the same issue?

[toots]

Thanks for this. Do you have more logs of the exception? Like perhaps the rest of the stack trace?

[ChoiZ]

It's exactly the same problem later in the night.

I use liquidsoap v2.0.4

I'm not sure it's link but in my config sometime when I enable the liq_cue_in or liq_cue_out in annotation he can't read songs and read only jingles (without liq_cue_in / liq_cue_out annotation), tell me if you need my config.

2022/05/19 01:12:13 [playlist:4] Finished with "/mnt/md0/maradio/audio/music/Rihanna - Pon de Replay.mp3".
2022/05/19 01:12:13 [playlist:4] Remaining 0 requests
2022/05/19 01:12:13 [playlist:3] Prepared "/mnt/md0/maradio/audio/music/[Jingle] - Liner des hits sans pub mixage final.mp3" (RID 10).
2022/05/19 01:12:13 [decoder.ogg:4] Unsupported file extension for "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3"!
2022/05/19 01:12:13 [decoder.aiff:4] Unsupported file extension for "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3"!
2022/05/19 01:12:13 [decoder.wav:4] Unsupported file extension for "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3"!
2022/05/19 01:12:13 [decoder:4] Available decoders: MAD (priority: 1)
2022/05/19 01:12:13 [decoder.mad:4] Libmad recognizes "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3" as mpeg audio (layer III, 192kbps, 44100Hz, 2 channels).
2022/05/19 01:12:13 [decoder:4] Selected decoder MAD for file "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3" with expected kind {audio=pcm(stereo),video=none,midi=none} and detected content {audio=pcm(stereo),video=none,midi=none}
2022/05/19 01:12:13 [decoder.ogg:4] Unsupported file extension for "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3"!
2022/05/19 01:12:13 [lang:3] data is not a liner
2022/05/19 01:12:13 [cue_cut_0:4] Cue points : none / none
2022/05/19 01:12:13 [cue_cut_0:3] Cueing in...
2022/05/19 01:12:13 [cross_0:3] Analysis: -14.343764dB / -8.680075dB (0.96s / 0.96s)
2022/05/19 01:12:13 [crossfade_0:4] Before: ("data_cue_in", "0.2")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("on_air_timestamp", "1652915328.00")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("liner", "disallow")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("kind", "{audio=pcm(stereo),video=none,midi=none}")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("artist", "Rihanna")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("liner_file", "/mnt/md0/maradio/audio/JinglesMaRadio/Voix/1 - Normalized/2022/Phrases/Jingle1.wav")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("decoder", "MAD")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("title", "Pon de Replay")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("type", "song")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("filename", "/mnt/md0/maradio/audio/music/Rihanna - Pon de Replay.mp3")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("temporary", "false")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("initial_uri", "annotate:type=\"song\",duration=\"244.454\",liner=\"disallow\",liner_delay=\"0\",liner_file=\"/mnt/md0/maradio/audio/JinglesMaRadio/Voix/1 - Normalized/2022/Phrases/Jingle1.wav\",data_cue_in=\"0.2\",data_cue_out=\"244.406\",title=\"Pon de Replay\",artist=\"Rihanna\",liq_fade_in=\"0.\",liq_fade_out=\"0.\":/mnt/md0/maradio/audio/music/Rihanna - Pon de Replay.mp3")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("liq_fade_out", "0.")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("data_cue_out", "244.406")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("liner_delay", "0")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("status", "playing")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("on_air", "2022/05/19 01:08:48")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("liq_fade_in", "0.")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("rid", "4")
2022/05/19 01:12:13 [crossfade_0:4] Before: ("duration", "244.454")
2022/05/19 01:12:13 [crossfade_0:4] After : ("on_air_timestamp", "1652915533.00")
2022/05/19 01:12:13 [crossfade_0:4] After : ("kind", "{audio=pcm(stereo),video=none,midi=none}")
2022/05/19 01:12:13 [crossfade_0:4] After : ("artist", "Ma Radio")
2022/05/19 01:12:13 [crossfade_0:4] After : ("decoder", "MAD")
2022/05/19 01:12:13 [crossfade_0:4] After : ("title", "Ma Radio")
2022/05/19 01:12:13 [crossfade_0:4] After : ("type", "jingle")
2022/05/19 01:12:13 [crossfade_0:4] After : ("filename", "/mnt/md0/maradio/audio/music/[Jingle] - Liner des hits sans pub mixage final.mp3")
2022/05/19 01:12:13 [crossfade_0:4] After : ("temporary", "false")
2022/05/19 01:12:13 [crossfade_0:4] After : ("initial_uri", "annotate:type=\"jingle\",title=\"Ma Radio\",artist=\"Ma Radio\",liq_fade_in=\"0.\",liq_fade_out=\"0.\":/mnt/md0/maradio/audio/music/[Jingle] - Liner des hits sans pub mixage final.mp3")
2022/05/19 01:12:13 [crossfade_0:4] After : ("liq_fade_out", "0.")
2022/05/19 01:12:13 [crossfade_0:4] After : ("status", "playing")
2022/05/19 01:12:13 [crossfade_0:4] After : ("on_air", "2022/05/19 01:12:13")
2022/05/19 01:12:13 [crossfade_0:4] After : ("liq_fade_in", "0.")
2022/05/19 01:12:13 [crossfade_0:4] After : ("rid", "10")
2022/05/19 01:12:13 [crossfade_0:3] Simple transition: crossed, fade-in, fade-out.
2022/05/19 01:12:13 [source:4] Source add_49 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [add_49:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source fade.in gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [fade.in:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source fade.in gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [fade.in:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source cross_0_after gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [cross_0_after:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source buffer_63 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [buffer_63:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source fade.out gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [fade.out:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source fade.out gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [fade.out:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source on_end_32 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [on_end_32:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source cross_0_before gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [cross_0_before:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [source:4] Source buffer_62 gets up with content kind: {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [buffer_62:4] Content type is {audio=pcm(stereo),video=none,midi=none}.
2022/05/19 01:12:13 [cross_0_after:4] New fade duration: 0.s.
2022/05/19 01:12:13 [cross_0_after:4] Fading in (type: sin, duration: 0.s).
2022/05/19 01:12:13 [cross_0_before:4] New fade duration: 0.s.
2022/05/19 01:12:14 [buffer_63:4] Buffer emptied, buffering needed.
2022/05/19 01:12:14 [buffer_62:4] Buffer emptied, buffering needed.
2022/05/19 01:12:14 [cross_0_before:4] Fading out with 0.s remaining.
2022/05/19 01:12:14 [source:4] Source add_49 gets down.
2022/05/19 01:12:14 [source:4] Source fade.in gets down.
2022/05/19 01:12:14 [source:4] Source fade.in gets down.
2022/05/19 01:12:14 [source:4] Source cross_0_after gets down.
2022/05/19 01:12:14 [source:4] Source buffer_63 gets down.
2022/05/19 01:12:14 [source:4] Source fade.out gets down.
2022/05/19 01:12:14 [source:4] Source fade.out gets down.
2022/05/19 01:12:14 [source:4] Source on_end_32 gets down.
2022/05/19 01:12:14 [source:4] Source cross_0_before gets down.
2022/05/19 01:12:14 [source:4] Source buffer_62 gets down.
2022/05/19 01:12:14 [cross_0:4] Buffering end of track...
2022/05/19 01:12:14 [cross_0:4] More buffering will be needed.
2022/05/19 01:12:16 [decoder:4] Decoding "/mnt/md0/maradio/audio/music/[Jingle] - Liner des hits sans pub mixage final.mp3" ended: Mad.End_of_stream.
2022/05/19 01:12:16 [decoder:4] Raised by primitive operation at Mad_decoder.init.get_data in file "decoder/mad_decoder.ml", line 49, characters 15-42
2022/05/19 01:12:16 [decoder:4] Called from Mad_decoder.create_decoder.(fun) in file "decoder/mad_decoder.ml", line 102, characters 19-30
2022/05/19 01:12:16 [decoder:4] Called from Decoder.opaque_file_decoder.decode in file "decoder/decoder.ml", line 571, characters 4-25
2022/05/19 01:12:16 [decoder:4] Called from Decoder.mk_decoder.fill in file "decoder/decoder.ml", line 500, characters 10-31
2022/05/19 01:12:16 [decoder:4] 
2022/05/19 01:12:16 [playlist:4] Finished with "/mnt/md0/maradio/audio/music/[Jingle] - Liner des hits sans pub mixage final.mp3".
2022/05/19 01:12:16 [playlist:4] Remaining 0 requests
2022/05/19 01:12:16 [playlist:3] Prepared "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3" (RID 16).
2022/05/19 01:12:16 [clock.main:4] Source Ma_Radio failed while streaming: Pcre.Error(_)!
2022/05/19 01:12:16 [clock.main:4] Raised by primitive operation at Pcre.regexp in file "src/pcre.ml", line 275, characters 11-38
2022/05/19 01:12:16 [clock.main:4] Called from Builtins_string.(fun) in file "lang/builtins_string.ml", line 435, characters 16-35
2022/05/19 01:12:16 [clock.main:4] Called from Evaluation.apply.f in file "lang/evaluation.ml", line 257, characters 8-12
2022/05/19 01:12:16 [clock.main:4] Called from Evaluation.eval in file "lang/evaluation.ml", line 159, characters 16-27
2022/05/19 01:12:16 [clock.main:4] Called from Evaluation.apply.f in file "lang/evaluation.ml", line 257, characters 8-12
2022/05/19 01:12:16 [clock.main:4] Called from Tutils.finalize in file "tools/tutils.ml", line 105, characters 12-16
2022/05/19 01:12:16 [clock.main:4] Re-raised at Tutils.finalize in file "tools/tutils.ml", line 111, characters 4-38
2022/05/19 01:12:16 [clock.main:4] Called from Lang.source_methods.(fun) in file "lang/lang.ml", line 647, characters 40-68
2022/05/19 01:12:16 [clock.main:4] Called from Stdlib__list.iter in file "list.ml", line 110, characters 12-15
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame.(fun) in file "source.ml", line 698, characters 12-47
2022/05/19 01:12:16 [clock.main:4] Called from Tutils.mutexify in file "tools/tutils.ml", line 95, characters 14-17
2022/05/19 01:12:16 [clock.main:4] Re-raised at Tutils.mutexify in file "tools/tutils.ml", line 101, characters 4-38
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 689, characters 6-373
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Cuepoint.cue_cut#get_frame in file "operators/cuepoint.ml", line 169, characters 6-20
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Cross.cross#analyze_after.f in file "operators/cross.ml", line 306, characters 10-30
2022/05/19 01:12:16 [clock.main:4] Called from Cross.cross#get_frame in file "operators/cross.ml", line 231, characters 36-54
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Map_metadata.map_metadata#get_frame in file "operators/map_metadata.ml", line 51, characters 6-20
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Switch.switch#get_frame in file "operators/switch.ml", line 263, characters 14-39
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Switch.switch#get_frame in file "operators/switch.ml", line 263, characters 14-39
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Insert_metadata.replay#get_frame in file "operators/insert_metadata.ml", line 133, characters 6-16
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Sequence.sequence#get_frame in file "operators/sequence.ml", line 81, characters 8-18
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Switch.switch#get_frame in file "operators/switch.ml", line 263, characters 14-39
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Window_op.window#get_frame in file "operators/window_op.ml", line 61, characters 6-20
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#instrumented_get_frame in file "source.ml", line 668, characters 6-24
2022/05/19 01:12:16 [clock.main:4] Called from Source.operator#get in file "source.ml", line 739, characters 10-41
2022/05/19 01:12:16 [clock.main:4] Called from Output.output#output in file "outputs/output.ml", line 165, characters 10-30
2022/05/19 01:12:16 [clock.main:4] Called from Clock.clock#end_tick.(fun) in file "clock.ml", line 299, characters 14-22
2022/05/19 01:12:16 [clock.main:4] 
2022/05/19 01:12:16 [source:4] Source Ma_Radio gets down.
2022/05/19 01:12:16 [Ma_Radio:3] Closing connection...
2022/05/19 01:12:16 [source:4] Source rms_0 gets down.
2022/05/19 01:12:16 [source:4] Source switch_3 gets down.
2022/05/19 01:12:16 [source:4] Source switch_0 gets down.
2022/05/19 01:12:16 [source:4] Source delay_0 gets down.
2022/05/19 01:12:16 [source:4] Source single_0 gets down.
2022/05/19 01:12:16 [single_0:4] Finished with "/mnt/md0/maradio/audio/JinglesMaRadio/Voix/1 - Normalized/2022/Phrases/Jingle1.wav".
2022/05/19 01:12:16 [source:4] Source sequence_34 gets down.
2022/05/19 01:12:16 [source:4] Source max_duration_25 gets down.
2022/05/19 01:12:16 [source:4] Source fail_8 gets down.
2022/05/19 01:12:16 [source:4] Source replay_metadata_82 gets down.
2022/05/19 01:12:16 [source:4] Source switch_2 gets down.
2022/05/19 01:12:16 [source:4] Source mksafe gets down.
2022/05/19 01:12:16 [source:4] Source safe_blank gets down.
2022/05/19 01:12:16 [source:4] Source crossfade_0 gets down.
2022/05/19 01:12:16 [source:4] Source cross_0 gets down.
2022/05/19 01:12:16 [source:4] Source cue_cut_0 gets down.
2022/05/19 01:12:16 [source:4] Source on_end_0 gets down.
2022/05/19 01:12:16 [source:4] Source playlist gets down.
2022/05/19 01:12:16 [playlist:4] Waiting for feeding task to stop...
2022/05/19 01:12:16 [playlist:4] Finished with "/mnt/md0/maradio/audio/music/Purple Disco Machine - Dopamine (feat. Eyelar).mp3".
2022/05/19 01:12:16 [playlist:4] Cleaning up request queue...
2022/05/19 01:12:16 [clock.main:3] Streaming loop stopped.
2022/05/19 01:12:16 [threads:4] Thread "clock_main" terminated (0 remaining).
2022/05/19 01:12:16 [main:3] Shutdown started!
2022/05/19 01:12:16 [main:3] Waiting for main threads to terminate...
2022/05/19 01:12:16 [main:3] Main threads terminated.
2022/05/19 01:12:16 [threads:3] Shutting down scheduler...
2022/05/19 01:12:16 [threads:4] Thread "non-blocking queue #2" terminated (3 remaining).
2022/05/19 01:12:16 [threads:4] Thread "generic queue #1" terminated (2 remaining).
2022/05/19 01:12:16 [threads:4] Thread "non-blocking queue #1" terminated (1 remaining).
2022/05/19 01:12:16 [threads:4] Thread "generic queue #2" terminated (0 remaining).
2022/05/19 01:12:16 [threads:3] Scheduler shut down.
2022/05/19 01:12:16 [main:3] Cleaning downloaded files...
2022/05/19 01:12:16 [main:3] Freeing memory...

[toots]

I'm gonna fix the underlying issue but could you also share more of your code? I'd like to see if the call is coming from us or from part of your setup.

[ChoiZ]

Error was due to string.replace in this piece of code:

def log_song(m)
  artist = m["artist"]
  title = m["title"]
  # remove .mp3
  title = string.replace(pattern=file.extension(title), fun (_) -> "", title) # <<< this line
  file.write(append=false, data="#{artist} - #{title}\n", "current.txt")
end

I don't use this code for now so I have removed it.

Thanks @toots you solved my problem.