Random Mix Continuous mode occasionally fails to add new tracks

[trevorwarwick]

Per the discussion on the Forum: https://forums.lyrion.org/forum/user-forums/logitech-media-server/113397-continuous-not-working-on-random-mix

Random Mix will occasionally fail to add new tracks. My experience is that this happens from the start of a new mix. It will fill up with the specified number of songs, but fail to add any more after the initial set.

To Reproduce
It's not deterministic, for me it fails once every few months, and needs a server reboot to fix it.

Expected behavior
Random Mix works as documented

Screenshots
If applicable, add screenshots to help explain your problem.

System Information (please complete the following information):
Debian Bookworm
Material
Chrome
LMS 8.x and latest released LMS 9
Squeezebox Touch

Additional context

from my forum post:

It's doing it again now. This was the log, so it looks like something fails almost as soon as the initial playlist gets set up- see the "cyclic mode ended message" as soon as the playback starts

[24-03-08 16:58:56.4579] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 10 items for type: track
[24-03-08 16:58:56.5850] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Playing track: #23804
[24-03-08 16:58:56.5986] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #22212
[24-03-08 16:58:56.6007] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #23345
[24-03-08 16:58:56.6025] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #24035
[24-03-08 16:58:56.6041] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #26209
[24-03-08 16:58:56.6057] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #25717
[24-03-08 16:58:56.6072] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #25122
[24-03-08 16:58:56.6088] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #21064
[24-03-08 16:58:56.6103] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #23216
[24-03-08 16:58:56.6117] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #22961
[24-03-08 16:58:56.6148] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 10 items
[24-03-08 16:58:56.6201] Slim::Plugin::RandomPlay::Plugin::commandCallback (1018) Cyclic mode ending due to playlist: playlist loadtracks command
[24-03-08 16:58:56.6204] Slim::Plugin::RandomPlay::Mixer::playRandom (307) Cyclic mode ended

A later working example (with only 2 tracks prefetched this time)

[24-03-07 18:16:09.8944] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 2 items for type: track
[24-03-07 18:16:10.0234] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Playing track: #26725
[24-03-07 18:16:10.0444] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #27111
[24-03-07 18:16:10.0488] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 2 items
[24-03-07 18:16:10.5891] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (0)
[24-03-07 18:16:17.0367] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (0)
[24-03-07 18:16:27.0022] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:16:27.0038] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #23138
[24-03-07 18:16:27.0082] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 3 items
[24-03-07 18:17:56.0018] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (1)
[24-03-07 18:18:05.0069] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:18:05.0082] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #21342
[24-03-07 18:18:05.0124] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 4 items
[24-03-07 18:21:18.0079] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (2)
[24-03-07 18:21:28.0098] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:21:28.0121] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #22718
[24-03-07 18:21:28.0165] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 5 items
[24-03-07 18:24:39.0051] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (3)
[24-03-07 18:24:39.0053] Slim::Plugin::RandomPlay::Plugin::commandCallback (991) Stripping off 1 completed track(s)
[24-03-07 18:24:49.0121] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:24:49.0134] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #21609
[24-03-07 18:24:49.0174] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 5 items
[24-03-07 18:27:09.0014] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (3)
[24-03-07 18:27:09.0015] Slim::Plugin::RandomPlay::Plugin::commandCallback (991) Stripping off 1 completed track(s)
[24-03-07 18:27:18.0022] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:27:18.0037] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #20970
[24-03-07 18:27:18.0078] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 5 items
[24-03-07 18:30:55.0038] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (3)
[24-03-07 18:30:55.0039] Slim::Plugin::RandomPlay::Plugin::commandCallback (991) Stripping off 1 completed track(s)
[24-03-07 18:31:04.0095] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:31:04.0109] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #22164
[24-03-07 18:31:04.0150] Slim::Plugin::RandomPlay::Mixer::playRandom (325) Playing continuous track mode with 5 items
[24-03-07 18:34:51.0028] Slim::Plugin::RandomPlay::Plugin::commandCallback (977) New song detected (3)
[24-03-07 18:34:51.0030] Slim::Plugin::RandomPlay::Plugin::commandCallback (991) Stripping off 1 completed track(s)
[24-03-07 18:35:00.0023] Slim::Plugin::RandomPlay::Mixer::findAndAdd (31) Starting random selection of 1 items for type: track
[24-03-07 18:35:00.0038] Slim::Plugin::RandomPlay::Mixer::findAndAdd (61) Adding track: #21116

[michaelherger]

At some point I concluded that RandomPlay seems to not recognise its own commands any more. Those requests should be tagged with RandomPlay as the source, but that seems to be broken under certain circumstances.

[michaelherger]

Do we know whether DSTM is/is not involved in these cases? Do all the users reporting use DSTM, too?

[trevorwarwick]

I have DSTM enabled as a plugin. But it is Disabled on all my players in the Audio config screen.

[wawa79]

I am struggling with this issue for a while : sometimes the 'infinite' icon of DSTM + a weird 'ramdom mix' queued (the 'surprise mix' as I call it as you don't know what is coming next...) and sometimes the 'dice' icon from Random mix plugin which fills properly the queue with new tracks.
I have both DSTM and random mix plugin enabled. I just disabled DSTM and the dice immediately came back. On my side, I will leave DSTM disabled as I do not like that queuing logic and report if the Random mix plugin fails filling the queue at some point.