Oct 13 11:37:00 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 11:37:00 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 11:37:03 rivo go-librespot[8126]: time="2026-10-13T11:37:03+02:00" level=debug msg="fetched chunk 20/24, size: 524288" uri="spotify:track:77HsDRybYI2rp92WVyC28R" Oct 13 11:37:06 rivo volumio[5970]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 13 11:37:06 rivo volumio[5970]: info: CoreStateMachine::removeQueueItem Oct 13 11:37:06 rivo volumio[5970]: info: CoreStateMachine::stop Oct 13 11:37:06 rivo volumio[5970]: info: CoreStateMachine::serviceStop Oct 13 11:37:06 rivo volumio[5970]: info: CoreCommandRouter::serviceStop Oct 13 11:37:06 rivo volumio[5970]: info: Spotify Stop Oct 13 11:37:06 rivo volumio[5970]: SPOTIFY: SPOTIFY STOP Oct 13 11:37:06 rivo volumio[5970]: SPOTIFY: {"status":"play","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","codec":"ogg","seek":0,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Oct 13 11:37:06 rivo volumio[5970]: info: Sending Spotify command to local API: /player/pause Oct 13 11:37:06 rivo volumio[5970]: info: CorePlayQueue::removeQueueItem 0 Oct 13 11:37:06 rivo volumio[5970]: info: CorePlayQueue::saveQueue Oct 13 11:37:06 rivo volumio[5970]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:37:06 rivo go-librespot[8126]: time="2026-10-13T11:37:06+02:00" level=debug msg="pause track at 212440ms" Oct 13 11:37:06 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Oct 13 11:37:06 rivo kernel: spdif_a keep clk continuous Oct 13 11:37:06 rivo kernel: aml_spdif_close Oct 13 11:37:06 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 13 11:37:06 rivo go-librespot[8126]: time="2026-10-13T11:37:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:06 rivo go-librespot[8126]: time="2026-10-13T11:37:06+02:00" level=trace msg="emitting websocket event: paused" Oct 13 11:37:07 rivo volumio[5970]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:4Sge0HQ5c9GStquIFSLqlA","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","play_origin":"playlist"}} Oct 13 11:37:07 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:07 rivo volumio[5970]: SPOTIFY: {"status":"pause","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":212000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:07 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:07 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:07 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:07 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:07 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:07 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:07 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:07 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:07.023+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PAUSED positionMs=212000 volume=100 Oct 13 11:37:07 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:07.024+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:07 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:07 rivo go-librespot[8126]: time="2026-10-13T11:37:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:19 rivo volumio[5970]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Oct 13 11:37:19 rivo volumio[5970]: info: CoreStateMachine::getQueue Oct 13 11:37:19 rivo volumio[5970]: info: CorePlayQueue::getQueue Oct 13 11:37:19 rivo volumio[5970]: info: Saving local item undefined Oct 13 11:37:23 rivo volumio[5970]: info: CoreCommandRouter::volumioVolatilePlay Oct 13 11:37:23 rivo volumio[5970]: info: CoreStateMachine::volatilePlay Oct 13 11:37:23 rivo volumio[5970]: info: Spotify Play Oct 13 11:37:23 rivo volumio[5970]: info: Sending Spotify command to local API: /player/resume Oct 13 11:37:23 rivo go-librespot[8126]: time="2026-10-13T11:37:23+02:00" level=trace msg="seek to 212440ms (diff: 183ms, samples: 9368604, bytes: 9017657)" uri="spotify:track:77HsDRybYI2rp92WVyC28R" Oct 13 11:37:24 rivo go-librespot[8126]: time="2026-10-13T11:37:24+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 13 11:37:24 rivo kernel: aml_spdif_open Oct 13 11:37:24 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 13 11:37:24 rivo kernel: set normal 512 fs /4 fs Oct 13 11:37:24 rivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 13 11:37:24 rivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 13 11:37:24 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 13 11:37:24 rivo kernel: set normal 512 fs /4 fs Oct 13 11:37:24 rivo kernel: set spdifout clk:5644800, mpll:22579200 Oct 13 11:37:24 rivo kernel: get spdifout clk:5644797, mpll:22579186 Oct 13 11:37:24 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 13 11:37:24 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Oct 13 11:37:24 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 13 11:37:24 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Oct 13 11:37:24 rivo go-librespot[8126]: time="2026-10-13T11:37:24+02:00" level=debug msg="resume track at 212257ms" Oct 13 11:37:24 rivo go-librespot[8126]: time="2026-10-13T11:37:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:24 rivo go-librespot[8126]: time="2026-10-13T11:37:24+02:00" level=trace msg="scheduling prefetch in 64s" Oct 13 11:37:24 rivo go-librespot[8126]: time="2026-10-13T11:37:24+02:00" level=trace msg="emitting websocket event: playing" Oct 13 11:37:24 rivo volumio[5970]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:4Sge0HQ5c9GStquIFSLqlA","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","resume":true,"play_origin":"playlist"}} Oct 13 11:37:24 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:24 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":212000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:24 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:24 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:24 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:24 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:24.082+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=212000 volume=100 Oct 13 11:37:24 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:24.083+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:24 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:24 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 13 11:37:24 rivo go-librespot[8126]: time="2026-10-13T11:37:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:24 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:24 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":212000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:24 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:24 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:24 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:24 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:24 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:24.388+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=212000 volume=100 Oct 13 11:37:24 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:24.389+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:24 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:28 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:28 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:28 rivo volumio[5970]: info: CoreStateMachine::setRandom true Oct 13 11:37:28 rivo volumio[5970]: info: Spotify Random: true Oct 13 11:37:28 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/shuffle_context Oct 13 11:37:28 rivo go-librespot[8126]: time="2026-10-13T11:37:28+02:00" level=debug msg="shuffled context with seed 17515792794431332024 (len: 408, keep: 165)" uri="spotify:playlist:4Sge0HQ5c9GStquIFSLqlA" Oct 13 11:37:28 rivo go-librespot[8126]: time="2026-10-13T11:37:28+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 13 11:37:28 rivo go-librespot[8126]: time="2026-10-13T11:37:28+02:00" level=trace msg="scheduling prefetch in 60s" Oct 13 11:37:28 rivo volumio[5970]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":true}} Oct 13 11:37:28 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:28 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":216000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:28 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:28 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:28 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:28 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:28 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:28 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:28 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:28 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:28.250+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=216000 volume=100 Oct 13 11:37:28 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:28.255+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:28 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:28 rivo go-librespot[8126]: time="2026-10-13T11:37:28+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:29 rivo volumio[5970]: info: CoreStateMachine::setRandom false Oct 13 11:37:29 rivo volumio[5970]: info: Spotify Random: false Oct 13 11:37:29 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/shuffle_context Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=debug msg="unshuffled context with seed 17515792794431332024 (len: 408, keep: 165)" uri="spotify:playlist:4Sge0HQ5c9GStquIFSLqlA" Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=trace msg="scheduling prefetch in 59s" Oct 13 11:37:29 rivo volumio[5970]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":false}} Oct 13 11:37:29 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:29 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":217000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:29 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:29 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:29 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:29 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:29.171+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=217000 volume=100 Oct 13 11:37:29 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:29.172+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:29 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:29 rivo volumio[5970]: info: CoreStateMachine::setRandom true Oct 13 11:37:29 rivo volumio[5970]: info: Spotify Random: true Oct 13 11:37:29 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/shuffle_context Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=debug msg="shuffled context with seed 11643533730334275592 (len: 408, keep: 238)" uri="spotify:playlist:4Sge0HQ5c9GStquIFSLqlA" Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=trace msg="scheduling prefetch in 59s" Oct 13 11:37:29 rivo volumio[5970]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":true}} Oct 13 11:37:29 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:29 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":217000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:29 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:29 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:29 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:29 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:29 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:29.752+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=217000 volume=100 Oct 13 11:37:29 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:29.752+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:29 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:29 rivo go-librespot[8126]: time="2026-10-13T11:37:29+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:30 rivo volumio[5970]: info: CoreStateMachine::setRandom false Oct 13 11:37:30 rivo volumio[5970]: info: Spotify Random: false Oct 13 11:37:30 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/shuffle_context Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=debug msg="unshuffled context with seed 11643533730334275592 (len: 408, keep: 238)" uri="spotify:playlist:4Sge0HQ5c9GStquIFSLqlA" Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=trace msg="scheduling prefetch in 58s" Oct 13 11:37:30 rivo volumio[5970]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":false}} Oct 13 11:37:30 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:30 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":218000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:30 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:30 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:30 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:30 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:30.290+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=218000 volume=100 Oct 13 11:37:30 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:30.292+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:30 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:30 rivo volumio[5970]: info: CoreStateMachine::setRandom true Oct 13 11:37:30 rivo volumio[5970]: info: Spotify Random: true Oct 13 11:37:30 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/shuffle_context Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=debug msg="shuffled context with seed 15775691511161901426 (len: 408, keep: 81)" uri="spotify:playlist:4Sge0HQ5c9GStquIFSLqlA" Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=trace msg="scheduling prefetch in 58s" Oct 13 11:37:30 rivo volumio[5970]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":true}} Oct 13 11:37:30 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:30 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":218000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:30 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:30 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:30 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:30 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:30 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:30.715+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=218000 volume=100 Oct 13 11:37:30 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:30.719+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:30 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:30 rivo go-librespot[8126]: time="2026-10-13T11:37:30+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:32 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:32 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:32 rivo volumio[5970]: info: CoreStateMachine::setRepeat true single false Oct 13 11:37:32 rivo volumio[5970]: info: Spotify Repeat: true - false Oct 13 11:37:32 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/repeat_context Oct 13 11:37:32 rivo go-librespot[8126]: time="2026-10-13T11:37:32+02:00" level=trace msg="emitting websocket event: repeat_context" Oct 13 11:37:32 rivo go-librespot[8126]: time="2026-10-13T11:37:32+02:00" level=trace msg="scheduling prefetch in 56s" Oct 13 11:37:32 rivo volumio[5970]: SPOTIFY: received: {"type":"repeat_context","data":{"value":true}} Oct 13 11:37:32 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:32 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":220000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":true,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 13 11:37:32 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:32 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:32 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:32 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:32 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:32 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:32 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:32 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:32.706+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=220000 volume=100 Oct 13 11:37:32 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:32.706+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:32 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:32 rivo go-librespot[8126]: time="2026-10-13T11:37:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:33 rivo volumio[5970]: info: CoreStateMachine::setRandom false Oct 13 11:37:33 rivo volumio[5970]: info: Spotify Random: false Oct 13 11:37:33 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/shuffle_context Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=debug msg="unshuffled context with seed 15775691511161901426 (len: 408, keep: 81)" uri="spotify:playlist:4Sge0HQ5c9GStquIFSLqlA" Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=trace msg="scheduling prefetch in 55s" Oct 13 11:37:33 rivo volumio[5970]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":false}} Oct 13 11:37:33 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:33 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":221000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":true,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:33 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:33 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:33 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:33 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:33.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=221000 volume=100 Oct 13 11:37:33 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:33.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:33 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=debug msg="fetched chunk 21/24, size: 524288" uri="spotify:track:77HsDRybYI2rp92WVyC28R" Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:33 rivo volumio[5970]: info: CoreStateMachine::setRepeat true single true Oct 13 11:37:33 rivo volumio[5970]: info: Spotify Repeat: true - true Oct 13 11:37:33 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/repeat_track Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=trace msg="emitting websocket event: repeat_track" Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=trace msg="scheduling prefetch in 55s" Oct 13 11:37:33 rivo volumio[5970]: SPOTIFY: received: {"type":"repeat_track","data":{"value":true}} Oct 13 11:37:33 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:33 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":221000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":true,"repeatSingle":true,"stream":false,"repeatMode":"all"} Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:33 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:33 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:33 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:33 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:33 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:33.911+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=221000 volume=100 Oct 13 11:37:33 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:33.912+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:33 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:33 rivo go-librespot[8126]: time="2026-10-13T11:37:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:34 rivo volumio[5970]: info: CoreCommandRouter::volumioRandom Oct 13 11:37:34 rivo volumio[5970]: info: CoreCommandRouter::writePlayerControls Oct 13 11:37:34 rivo volumio[5970]: info: CoreStateMachine::setRepeat false single false Oct 13 11:37:34 rivo volumio[5970]: info: Spotify Repeat: false - false Oct 13 11:37:34 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/repeat_context Oct 13 11:37:34 rivo volumio[5970]: info: Sending Spotify command with payload to local API: /player/repeat_track Oct 13 11:37:34 rivo go-librespot[8126]: time="2026-10-13T11:37:34+02:00" level=trace msg="emitting websocket event: repeat_context" Oct 13 11:37:34 rivo go-librespot[8126]: time="2026-10-13T11:37:34+02:00" level=trace msg="scheduling prefetch in 53s" Oct 13 11:37:34 rivo volumio[5970]: SPOTIFY: received: {"type":"repeat_context","data":{"value":false}} Oct 13 11:37:34 rivo volumio[5970]: SPOTIFY: PUSH STATE SPOTIFY Oct 13 11:37:34 rivo volumio[5970]: SPOTIFY: {"status":"play","service":"spop","title":"Here but I'm Gone","artist":"Vanessa Fernandez","album":"Use Me","albumart":"https://i.scdn.co/image/ab67616d00001e020b45775624d5d394c697bb99","uri":"spotify:track:77HsDRybYI2rp92WVyC28R","trackType":"spotify","seek":222000,"duration":306,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 13 11:37:34 rivo volumio[5970]: info: CoreCommandRouter::servicePushState Oct 13 11:37:34 rivo volumio[5970]: info: CoreStateMachine::pushState Oct 13 11:37:34 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:37:34 rivo volumio[5970]: info: CoreCommandRouter::volumioPushState Oct 13 11:37:34 rivo volumio[5970]: info: CoreCommandRouter::volumioGetState Oct 13 11:37:34 rivo volumio[5970]: info: MRS: Pushing multiroomSync output update for this device Oct 13 11:37:34 rivo volumio[5970]: info: MRS: Pushing multiroomSync output Oct 13 11:37:34 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:34.974+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" state=STATUS_PLAYING positionMs=222000 volume=100 Oct 13 11:37:34 rivo volumio5-onboarding[4008]: time=2026-10-13T11:37:34.974+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.214:49214 @ 0x237c4b0" id=spotify:track:77HsDRybYI2rp92WVyC28R title="Here but I'm Gone" Oct 13 11:37:34 rivo volumio[5970]: info: Updating RAAT Signal Path Oct 13 11:37:35 rivo go-librespot[8126]: time="2026-10-13T11:37:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:35 rivo go-librespot[8126]: time="2026-10-13T11:37:35+02:00" level=trace msg="emitting websocket event: repeat_track" Oct 13 11:37:35 rivo go-librespot[8126]: time="2026-10-13T11:37:35+02:00" level=trace msg="scheduling prefetch in 53s" Oct 13 11:37:35 rivo volumio[5970]: SPOTIFY: received: {"type":"repeat_track","data":{"value":false}} Oct 13 11:37:35 rivo go-librespot[8126]: time="2026-10-13T11:37:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 11:37:46 rivo go-librespot[8126]: time="2026-10-13T11:37:46+02:00" level=debug msg="fetched chunk 22/24, size: 524288" uri="spotify:track:77HsDRybYI2rp92WVyC28R" Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 11:37:59 rivo go-librespot[8126]: time="2026-10-13T11:37:59+02:00" level=debug msg="fetched chunk 23/24, size: 524288" uri="spotify:track:77HsDRybYI2rp92WVyC28R" Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 11:37:59 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:38:06 rivo volumio[5970]: info: Retrieving Cloud Streaming UI Oct 13 11:38:06 rivo volumio[5970]: info: Getting Tidal Cloud Configuration Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:38:06 rivo volumio[5970]: info: Getting Qobuz Cloud Configuration Oct 13 11:38:06 rivo volumio[5970]: info: Asking plugin for UI Config Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:38:06 rivo volumio[5970]: info: Getting Spotify Cloud Configuration Oct 13 11:38:06 rivo volumio[5970]: info: Asking plugin for UI Config Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:38:06 rivo volumio[5970]: info: Saving Spotify Acccount Oct 13 11:38:06 rivo volumio[5970]: info: Got it Oct 13 11:38:06 rivo volumio[5970]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 13 11:38:06 rivo volumio[5970]: info: Got Tidal Cloud Configuration Oct 13 11:38:06 rivo volumio[5970]: info: Got it Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 11:38:06 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 13 11:38:10 rivo volumio[5970]: info: Disabling MyMusic plugin metavolumio Oct 13 11:38:10 rivo volumio[5970]: info: Removing METAVOLUMIO REST API Endpoints Oct 13 11:38:10 rivo volumio[5970]: info: Removing metavolumio REST Endpoint Oct 13 11:38:10 rivo volumio[5970]: info: Removing getSimilarArtists REST Endpoint Oct 13 11:38:10 rivo volumio[5970]: info: Removing getSimilarAlbums REST Endpoint Oct 13 11:38:10 rivo volumio[5970]: info: Removing getSimilarTracks REST Endpoint Oct 13 11:38:10 rivo volumio[5970]: info: Disabling plugin metavolumio Oct 13 11:38:10 rivo volumio[5970]: info: Done. Oct 13 11:38:10 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 11:38:11 rivo go-librespot[8126]: time="2026-10-13T11:38:11+02:00" level=debug msg="fetched chunk 24/24, size: 314800" uri="spotify:track:77HsDRybYI2rp92WVyC28R" Oct 13 11:38:14 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 11:38:18 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 11:38:22 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 11:38:24 rivo volumio[5970]: info: Disabling MyMusic plugin upnp Oct 13 11:38:24 rivo sudo[11650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 13 11:38:24 rivo sudo[11650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 11:38:24 rivo systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 13 11:38:25 rivo volumio[5970]: info: Enabling MyMusic plugin upnp Oct 13 11:38:25 rivo volumio[5970]: info: Enabling plugin upnp Oct 13 11:38:25 rivo volumio[5970]: info: Loading plugin "upnp"... Oct 13 11:38:25 rivo volumio[5970]: info: [1791884305358] Starting Upmpd Daemon Oct 13 11:38:25 rivo volumio[5970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 11:38:25 rivo volumio[5970]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 11:38:25 rivo volumio[5970]: Error: listen EADDRINUSE: address already in use :::6599 Oct 13 11:38:25 rivo volumio[5970]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Oct 13 11:38:25 rivo volumio[5970]: at listenInCluster (node:net:1920:12) Oct 13 11:38:25 rivo volumio[5970]: at Server.listen (node:net:2008:7) Oct 13 11:38:25 rivo volumio[5970]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 13 11:38:25 rivo volumio[5970]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 13 11:38:25 rivo volumio[5970]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 13 11:38:25 rivo volumio[5970]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 13 11:38:25 rivo volumio[5970]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 13 11:38:25 rivo volumio[5970]: code: 'EADDRINUSE', Oct 13 11:38:25 rivo volumio[5970]: errno: -98, Oct 13 11:38:25 rivo volumio[5970]: syscall: 'listen', Oct 13 11:38:25 rivo volumio[5970]: address: '::', Oct 13 11:38:25 rivo volumio[5970]: port: 6599 Oct 13 11:38:25 rivo volumio[5970]: } Oct 13 11:38:25 rivo volumio[5970]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 11:38:26 rivo sudo[11662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 11:37' Oct 13 11:38:26 rivo sudo[11662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"