Hello!
I've noticed that, after a few days' operation, crossfading stops
working. Tracks still play, but they're not blended.
This seems to co-incide with no further appearances in the debug log
of the words "buffer" or "buffering". Here's a complete log from one
particular transition, the last time "buffer" appears:
2019/02/17 23:38:01 [decoder:4] Decoding "/home/john/src/radio/mez/06
Dream Waves.xJCMGQ.mka" ended: Ffmpeg_decoder.End_of_file.
2019/02/17 23:38:01 [test-nodup-shuf-processed(dot)m3u8:3] Finished
with "/home/john/src/radio/mez/06 Dream Waves.xJCMGQ.mka".
2019/02/17 23:38:01 [ffmpeg:4] [AVIOContext @ 0x7f292470b800]
Statistics: 4538470 bytes read, 0 seeks
2019/02/17 23:38:01 [amplify_10225:3] End of the current overriding.
2019/02/17 23:38:01 [override:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2019/02/17 23:38:01 [ffmpeg:4] [NULL @ 0x7f292446b3c0] Opening
'/home/john/src/radio/bbcuknews.mka' for reading
2019/02/17 23:38:01 [ffmpeg:4] [file @ 0x7f292447ebc0] Setting
default whitelist 'file,crypto'
2019/02/17 23:38:01 [ffmpeg:4] Probing matroska,webm score:100 size:2048
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
Format matroska,webm probed with size=2048 and score=100
2019/02/17 23:38:01 [ffmpeg:4] st:0 removing common factor 1000000
from timebase
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
Before avformat_find_stream_info() pos: 677 bytes read:32768 seeks:0
nb_streams:1
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0] All info found
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
stream 0: start_time: 0.000 duration: -9223372036854776.000
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
format: start_time: 0.000 duration: 178.453 bitrate=119 kb/s
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0] After
avformat_find_stream_info() pos: 708 bytes read:32768 seeks:0 frames:1
2019/02/17 23:38:01 [ffmpeg:4] [AVIOContext @ 0x7f29240e7440]
Statistics: 32768 bytes read, 0 seeks
2019/02/17 23:38:01 [ffmpeg:4] [NULL @ 0x7f292446b3c0] Opening
'/home/john/src/radio/bbcuknews.mka' for reading
2019/02/17 23:38:01 [ffmpeg:4] [file @ 0x7f2924104180] Setting
default whitelist 'file,crypto'
2019/02/17 23:38:01 [ffmpeg:4] Probing matroska,webm score:100 size:2048
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
Format matroska,webm probed with size=2048 and score=100
2019/02/17 23:38:01 [ffmpeg:4] st:0 removing common factor 1000000
from timebase
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
Before avformat_find_stream_info() pos: 677 bytes read:32768 seeks:0
nb_streams:1
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0] All info found
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
stream 0: start_time: 0.000 duration: -9223372036854776.000
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0]
format: start_time: 0.000 duration: 178.453 bitrate=119 kb/s
2019/02/17 23:38:01 [ffmpeg:4] [matroska,webm @ 0x7f292446b3c0] After
avformat_find_stream_info() pos: 708 bytes read:32768 seeks:0 frames:1
2019/02/17 23:38:01 [ffmpeg:4] [SWR @ 0x7f292583dd00] Using fltp
internally between filters
2019/02/17 23:38:01 [override:3] Prepared
"/home/john/src/radio/bbcuknews.mka" (RID 5).
2019/02/17 23:38:01 [fallback_10228:3] Switch to override with
forgetful transition.
2019/02/17 23:38:01 [add_21374:4] Activations changed: static=[], dynamic=[].
2019/02/17 23:38:01 [source:4] Source add_21374 gets down.
2019/02/17 23:38:01 [fade_in_21373:4] Activations changed: static=[],
dynamic=[].
2019/02/17 23:38:01 [source:4] Source fade_in_21373 gets down.
2019/02/17 23:38:01 [amplify_10225:4] Activations changed: static=[],
dynamic=[fallback_10228:fade_in_10230:fade_out_10231:cross_10232].
2019/02/17 23:38:01 [empty_21372:4] Activations changed: static=[], dynamic=[].
2019/02/17 23:38:01 [source:4] Source empty_21372 gets down.
2019/02/17 23:38:01 [override:4] Activations changed:
static=[fallback_10228:fade_in_10230:fade_out_10231:cross_10232],
dynamic=[fallback_10228:fade_in_10230:fade_out_10231:
cross_10232].
2019/02/17 23:38:01 [source:4] Source add_21401 gets up.
2019/02/17 23:38:01 [add_21401:4] Content kind is {audio=2;video=0;midi=0}.
2019/02/17 23:38:01 [fade_out_10231:4] Activations changed:
static=[add_21401:cross_10232, add_21394:cross_10232],
dynamic=[cross_10232].
2019/02/17 23:38:01 [fade_out_10231:4] Enabling caching mode: two
static activations.
2019/02/17 23:38:01 [source:4] Source buffer_21400 gets up.
2019/02/17 23:38:01 [buffer_21400:4] Content kind is {audio=2;video=0;midi=0}.
2019/02/17 23:38:01 [buffer_21400:4] Activations changed:
static=[add_21401:cross_10232], dynamic=[].
2019/02/17 23:38:01 [add_21401:4] Activations changed:
static=[cross_10232], dynamic=[].
2019/02/17 23:38:01 [add_21394:4] Activations changed: static=[], dynamic=[].
2019/02/17 23:38:01 [source:4] Source add_21394 gets down.
2019/02/17 23:38:01 [fade_out_10231:4] Activations changed:
static=[add_21401:cross_10232], dynamic=[cross_10232].
2019/02/17 23:38:01 [fade_out_10231:4] Disabling caching mode.
2019/02/17 23:38:01 [buffer_21393:4] Activations changed: static=[],
dynamic=[].
2019/02/17 23:38:01 [source:4] Source buffer_21393 gets down.
2019/02/17 23:38:06 [buffer_21400:4] End of track.
2019/02/17 23:38:06 [buffer_21400:4] Buffer emptied, buffering needed.
2019/02/17 23:38:07 [cross_10232:4] Buffering end of track...
2019/02/17 23:38:07 [cross_10232:4] More buffering will be needed.
2019/02/17 23:38:08 [source:4] Garbage collected empty_21399.
2019/02/17 23:38:08 [source:4] Garbage collected add_21394.
2019/02/17 23:38:08 [source:4] Garbage collected buffer_21393.
2019/02/17 23:38:08 [source:4] Garbage collected add_21374.
2019/02/17 23:38:08 [source:4] Garbage collected fade_in_21373.
2019/02/17 23:38:08 [source:4] Garbage collected empty_21372.
2019/02/17 23:40:59 [decoder:4] Decoding
"/home/john/src/radio/bbcuknews.mka" ended:
Ffmpeg_decoder.End_of_file.
2019/02/17 23:40:59 [override:3] Finished with
"/home/john/src/radio/bbcuknews.mka".
2019/02/17 23:40:59 [ffmpeg:4] [AVIOContext @ 0x7f2924d6eb00]
Statistics: 2661363 bytes read, 0 seeks
2019/02/17 23:40:59 [test-nodup-shuf-processed(dot)m3u8:4] Remaining:
0.0s, queued: 266.4s, taking: 266.4s
2019/02/17 23:40:59 [ffmpeg:4] [NULL @ 0x7f292446b3c0] Opening
'/home/john/src/radio/mez/16 Let Me Fix It.pDVmVd.mka' for reading
2019/02/17 23:40:59 [ffmpeg:4] [file @ 0x7f29250f1140] Setting
default whitelist 'file,crypto'
2019/02/17 23:40:59 [ffmpeg:4] Probing matroska,webm score:100 size:2048
...etc.
This is the last place where "buffering" messages appear. Since this
point, tracks have played, but there has been no cross-fading, and no
messages containing the word "buffer" appear. By that, I mean the word
"buffer" appears nowhere in the log. It is never referenced from that
point onwards.
Nothing that looks like an error is recorded.
I wonder if there is something to do with buffers that I could adjust?
with best wishes,
JW
_______________________________________________
Savonet-users mailing list
Savonet-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/savonet-users
