Feb 12 12:52:11 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:11-05:00" level=trace msg="sent dealer ping" Feb 12 12:52:11 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:11-05:00" level=debug msg="fetched chunk 5/17, size: 524288" uri="spotify:track:75vSIO1nZtPcroF9H7lbp2" Feb 12 12:52:11 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:11-05:00" level=trace msg="received dealer pong" Feb 12 12:52:25 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:25-05:00" level=debug msg="fetched chunk 6/17, size: 524288" uri="spotify:track:75vSIO1nZtPcroF9H7lbp2" Feb 12 12:52:27 volumio-new-2026 volumio[1182]: error: error Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: Preload queue cleared Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::ClearQueue Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::stop Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::stPlaybackTimer Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::updateTrackBlock Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrackBlock Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::pushState Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushState Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::serviceStop Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::serviceStop Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: Spotify Stop Feb 12 12:52:33 volumio-new-2026 volumio[1182]: SPOTIFY: SPOTIFY STOP Feb 12 12:52:33 volumio-new-2026 volumio[1182]: SPOTIFY: {"status":"play","position":0,"title":"Carol of the Bells","artist":"George Winston","album":"December","albumart":"https://i.scdn.co/image/ab67616d0000b27334b5d6452cb77054fe78f700","uri":"spotify:track:75vSIO1nZtPcroF9H7lbp2","trackType":"spotify","codec":"ogg","seek":9514,"duration":240,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"repeat":true,"repeatSingle":false,"consume":false,"volume":83,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: Sending Spotify command to local API: /player/pause Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CorePlayQueue::clearPlayQueue Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CorePlayQueue::saveQueue Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushQueue Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CoreStateMachine::addQueueItems Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: CorePlayQueue::addQueueItems Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: Preload queue cleared Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: Adding Item to queue: spotify:artist:4Eun8YBC7P0psGdIf0GRtl Feb 12 12:52:33 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:artist:4Eun8YBC7P0psGdIf0GRtl in service spop Feb 12 12:52:33 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:artist:4Eun8YBC7P0psGdIf0GRtl Feb 12 12:52:33 volumio-new-2026 volumio[1182]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Feb 12 12:52:33 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:33-05:00" level=debug msg="pause track at 50752ms" Feb 12 12:52:33 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:33-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=trace msg="emitting websocket event: paused" Feb 12 12:52:34 volumio-new-2026 volumio[1182]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:75vSIO1nZtPcroF9H7lbp2","uri":"spotify:track:75vSIO1nZtPcroF9H7lbp2","play_origin":"go-librespot"}} Feb 12 12:52:34 volumio-new-2026 volumio[1182]: SPOTIFY: PUSH STATE SPOTIFY Feb 12 12:52:34 volumio-new-2026 volumio[1182]: SPOTIFY: {"status":"pause","service":"spop","title":"Carol of the Bells","artist":"George Winston","album":"December","albumart":"https://i.scdn.co/image/ab67616d00001e0234b5d6452cb77054fe78f700","uri":"spotify:track:75vSIO1nZtPcroF9H7lbp2","trackType":"spotify","seek":49000,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::servicePushState Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Carol of the Bells","artist":"George Winston","album":"December","albumart":"https://i.scdn.co/image/ab67616d00001e0234b5d6452cb77054fe78f700","uri":"spotify:track:75vSIO1nZtPcroF9H7lbp2","trackType":"spotify","seek":49000,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 12 12:52:34 volumio-new-2026 volumio[1182]: verbose: CURRENT POSITION 0 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::syncState stateService pause Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::syncState currentStatus stop Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::pushState Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushState Feb 12 12:52:34 volumio-new-2026 volumio[1182]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushQueue Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CorePlayQueue::saveQueue Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::updateTrackBlock Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrackBlock Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPlay Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::play index 0 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::stop Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::play index undefined Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CoreStateMachine::startPlaybackTimer Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: [1770918754855] ControllerSpotify::clearAddPlayTrack Feb 12 12:52:34 volumio-new-2026 volumio[1182]: info: Sending Spotify command with payload to local API: /player/play Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=debug msg="resolved context of track" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 12 12:52:34 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:34-05:00" level=trace msg="emitting websocket event: will_play" Feb 12 12:52:34 volumio-new-2026 volumio[1182]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","play_origin":"go-librespot"}} Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="selected format OGG_VORBIS_320 (aec570d9fff4518b41430f2aad16dee4ac61a25c)" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="requested aes key for file aec570d9fff4518b41430f2aad16dee4ac61a25c, gid: 1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="fetched first chunk of 16, total size is 8233156 bytes" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05: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" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=info msg="loaded track \"All Night Long\" (paused: false, position: 1ms, duration: 260156ms, prefetched: false)" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=trace msg="scheduling prefetch in 229s" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=trace msg="emitting websocket event: metadata" Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","name":"All Night Long","artist_names":["Pachyman","Winter"],"album_name":"El Sonido Nuevo de","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bf9cca3dbd724fd318f277fc","position":1,"duration":260156,"release_date":"year:2022 month:3 day:30","track_number":2,"disc_number":1}} Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=trace msg="emitting websocket event: playing" Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","resume":false,"play_origin":"go-librespot"}} Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: PUSH STATE SPOTIFY Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: {"status":"play","service":"spop","title":"All Night Long","artist":"Pachyman, Winter","album":"El Sonido Nuevo de","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9cca3dbd724fd318f277fc","uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","trackType":"spotify","seek":1,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::servicePushState Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"All Night Long","artist":"Pachyman, Winter","album":"El Sonido Nuevo de","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9cca3dbd724fd318f277fc","uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","trackType":"spotify","seek":1,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 12 12:52:35 volumio-new-2026 volumio[1182]: verbose: CURRENT POSITION 0 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreStateMachine::syncState stateService play Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreStateMachine::syncState currentStatus stop Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:35-05:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: PUSH STATE SPOTIFY Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: {"status":"play","service":"spop","title":"All Night Long","artist":"Pachyman, Winter","album":"El Sonido Nuevo de","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9cca3dbd724fd318f277fc","uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","trackType":"spotify","seek":1,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::servicePushState Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"All Night Long","artist":"Pachyman, Winter","album":"El Sonido Nuevo de","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9cca3dbd724fd318f277fc","uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","trackType":"spotify","seek":1,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 12 12:52:35 volumio-new-2026 volumio[1182]: verbose: CURRENT POSITION 0 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreStateMachine::syncState stateService play Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreStateMachine::syncState currentStatus play Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: Received an update from plugin. extracting info from payload Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreStateMachine::pushState Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushState Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreStateMachine::pushState Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushState Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Feb 12 12:52:35 volumio-new-2026 volumio[1182]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Feb 12 12:52:41 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:41-05:00" level=trace msg="sent dealer ping" Feb 12 12:52:41 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:41-05:00" level=trace msg="received dealer pong" Feb 12 12:52:42 volumio-new-2026 volumio[1182]: info: VolumeController::SetAlsaVolume73 Feb 12 12:52:42 volumio-new-2026 volumio[1182]: info: CoreStateMachine::pushState Feb 12 12:52:42 volumio-new-2026 volumio[1182]: info: CorePlayQueue::getTrack 0 Feb 12 12:52:42 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 12:52:42 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::volumioPushState Feb 12 12:52:42 volumio-new-2026 volumio[1182]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Feb 12 12:52:42 volumio-new-2026 volumio[1182]: SPOTIFY: SPOTIFY VOLUME 83 Feb 12 12:52:42 volumio-new-2026 volumio[1182]: SPOTIFY: VOLUMIO VOLUME 73 Feb 12 12:52:42 volumio-new-2026 volumio[1182]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 12 12:52:42 volumio-new-2026 volumio[1182]: info: Setting Spotify Volume from Volumio: 73 Feb 12 12:52:44 volumio-new-2026 volumio[1182]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Feb 12 12:52:44 volumio-new-2026 volumio[1182]: info: Sending Spotify command with payload to local API: /player/volume Feb 12 12:52:44 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:44-05:00" level=debug msg="update volume requested to 47840/65535" Feb 12 12:52:44 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:44-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 12 12:52:44 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:44-05:00" level=trace msg="emitting websocket event: volume" Feb 12 12:52:44 volumio-new-2026 volumio[1182]: SPOTIFY: received: {"type":"volume","data":{"value":73,"max":100}} Feb 12 12:52:44 volumio-new-2026 volumio[1182]: SPOTIFY: RECEIVED SPOTIFY VOLUME 73 Feb 12 12:52:50 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:52:50-05:00" level=debug msg="fetched chunk 4/15, size: 524288" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:53:06 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:53:06-05:00" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:1241XE9zZ2r4EmimoH9znZ" Feb 12 12:53:11 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:53:11-05:00" level=trace msg="sent dealer ping" Feb 12 12:53:11 volumio-new-2026 go-librespot[1688]: time="2026-02-12T12:53:11-05:00" level=trace msg="received dealer pong" Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preload queue cleared Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:1241XE9zZ2r4EmimoH9znZ Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:75vSIO1nZtPcroF9H7lbp2 Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:318jLr9oY0UrxYqyUHCfBA Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:1B62o4CbdL9ckGvwsz2cgn Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:5RqKo9P4DLA0bVyY70cWkr Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:1NZs6n6hl8UuMaX0UC0YTz Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:5gDWsRxpJ2lZAffh5p7K0w Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:24jvD83UgLmrdGjhWTFslY Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:6IfPyMb0Sxptpx6jBUATOS Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:5UzTtUZper0CphnqZYvsIu Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:2Kerz9H9IejzeIpjhDJoYG Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:4X5zaUdlRhvBWYnyQIKmH8 Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:1GNIYBU1XhMSlAxJXiUBbC Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:0ix3WleY9CWlQ1olYNoLLP Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:2mdEsXPu8ZmkHRRtAdC09e Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:2m7rcb8oxIgTDEC9DcPxhP Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:5OguRPsHrJrpHyEcNrpj7U Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:2sw8teIvVMRZKKYD5p795f Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:12bYYQaLqHliSXvRIYlq8G Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: https://stream.radioparadise.com/flacm Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:6qn9YLKt13AGvpq9jfO8py Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:4PEGwWH4tL6H7dGl4uVSPg Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:1Hv1VTm8zeOeybub15mA2R Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:2Rlso2ZNV0PaWwUYeeBYxx Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:1PfUKiMDKIlPEF9FfdJawc Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:6GGtHZgBycCgGBUhZo81xe Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:6GnihxGXNy1CRiiNrsqYeM Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:2XwjAUCmhAa6XccL9p0lGM Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: spotify:track:3VyJGPYr2DF9gdBNosSao4 Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: mnt/USB/5E65-7FFB/Size Matters Feelings WFMU/Unknown artist/Unknown album (1-2-2026 9-09-30 PM)/01 Track 1.mp3 Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth/motherearth Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Preloading song: http://stream.radioparadise.com/global-flacm Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:1241XE9zZ2r4EmimoH9znZ in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:1241XE9zZ2r4EmimoH9znZ Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:75vSIO1nZtPcroF9H7lbp2 in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:75vSIO1nZtPcroF9H7lbp2 Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:318jLr9oY0UrxYqyUHCfBA in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:318jLr9oY0UrxYqyUHCfBA Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:1B62o4CbdL9ckGvwsz2cgn in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:1B62o4CbdL9ckGvwsz2cgn Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:5RqKo9P4DLA0bVyY70cWkr in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:5RqKo9P4DLA0bVyY70cWkr Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1241XE9zZ2r4EmimoH9znZ","service":"spop","name":"All Night Long","artist":"Pachyman","album":"El Sonido Nuevo de","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf9cca3dbd724fd318f277fc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:1NZs6n6hl8UuMaX0UC0YTz in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:1NZs6n6hl8UuMaX0UC0YTz Feb 12 12:53:13 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:5gDWsRxpJ2lZAffh5p7K0w in service spop Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:5gDWsRxpJ2lZAffh5p7K0w Feb 12 12:53:13 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:75vSIO1nZtPcroF9H7lbp2","service":"spop","name":"Carol of the Bells","artist":"George Winston","album":"December","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b27334b5d6452cb77054fe78f700","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:24jvD83UgLmrdGjhWTFslY in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:24jvD83UgLmrdGjhWTFslY Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5RqKo9P4DLA0bVyY70cWkr","service":"spop","name":"saman","artist":"Ólafur Arnalds","album":"re:member","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c207f23ab7902473230ec0f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1B62o4CbdL9ckGvwsz2cgn","service":"spop","name":"Where's My Love","artist":"SYML","album":"SYML","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b273f975be33979060df30ac573a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:6IfPyMb0Sxptpx6jBUATOS in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:6IfPyMb0Sxptpx6jBUATOS Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1NZs6n6hl8UuMaX0UC0YTz","service":"spop","name":"Brooklyn Baby","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","type":"song","duration":351,"albumart":"https://i.scdn.co/image/ab67616d0000b2731624590458126fc8b8c64c2f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:5UzTtUZper0CphnqZYvsIu in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:5UzTtUZper0CphnqZYvsIu Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:318jLr9oY0UrxYqyUHCfBA","service":"spop","name":"The Arts and the Hours","artist":"Víkingur Ólafsson","album":"Debussy – Rameau","type":"song","duration":285,"albumart":"https://i.scdn.co/image/ab67616d0000b273caa124192bc4737787bca602","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5gDWsRxpJ2lZAffh5p7K0w","service":"spop","name":"Stargirl Interlude","artist":"The Weeknd","album":"Starboy","type":"song","duration":111,"albumart":"https://i.scdn.co/image/ab67616d0000b273a048415db06a5b6fa7ec4e1a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:2Kerz9H9IejzeIpjhDJoYG in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:2Kerz9H9IejzeIpjhDJoYG Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24jvD83UgLmrdGjhWTFslY","service":"spop","name":"Video Games","artist":"Lana Del Rey","album":"Born To Die - The Paradise Edition","type":"song","duration":281,"albumart":"https://i.scdn.co/image/ab67616d0000b273cb76604d9c5963544cf5be64","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:4X5zaUdlRhvBWYnyQIKmH8 in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:4X5zaUdlRhvBWYnyQIKmH8 Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:1GNIYBU1XhMSlAxJXiUBbC in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:1GNIYBU1XhMSlAxJXiUBbC Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6IfPyMb0Sxptpx6jBUATOS","service":"spop","name":"Old Money","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","type":"song","duration":271,"albumart":"https://i.scdn.co/image/ab67616d0000b2731624590458126fc8b8c64c2f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:0ix3WleY9CWlQ1olYNoLLP in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:0ix3WleY9CWlQ1olYNoLLP Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5UzTtUZper0CphnqZYvsIu","service":"spop","name":"Young And Beautiful","artist":"Lana Del Rey","album":"Young And Beautiful","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273254deb29b1e07b974e38c182","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:2mdEsXPu8ZmkHRRtAdC09e in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:2mdEsXPu8ZmkHRRtAdC09e Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4X5zaUdlRhvBWYnyQIKmH8","service":"spop","name":"Honeymoon","artist":"Lana Del Rey","album":"Honeymoon","type":"song","duration":350,"albumart":"https://i.scdn.co/image/ab67616d0000b273a3b3f48ca81acacb3ad4ec8a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Kerz9H9IejzeIpjhDJoYG","service":"spop","name":"Love","artist":"Lana Del Rey","album":"Lust For Life","type":"song","duration":272,"albumart":"https://i.scdn.co/image/ab67616d0000b27395e2fd1accb339fa14878190","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:2m7rcb8oxIgTDEC9DcPxhP in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:2m7rcb8oxIgTDEC9DcPxhP Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:5OguRPsHrJrpHyEcNrpj7U in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:5OguRPsHrJrpHyEcNrpj7U Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:2sw8teIvVMRZKKYD5p795f in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:2sw8teIvVMRZKKYD5p795f Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri spotify:track:12bYYQaLqHliSXvRIYlq8G in service spop Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: EXPLODING URI:spotify:track:12bYYQaLqHliSXvRIYlq8G Feb 12 12:53:14 volumio-new-2026 volumio[1182]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","service":"spop","name":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","type":"song","duration":300,"albumart":"https://i.scdn.co/image/ab67616d0000b273879e9318cb9f4e05ee552ac9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 12 12:53:14 volumio-new-2026 volumio[1182]: info: Exploding uri https://stream.radioparadise.com/flacm in service radio_paradise Feb 12 12:53:14 volumio-new-2026 volumio[1182]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/flacm" Feb 12 12:53:14 volumio-new-2026 volumio[1182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 12:53:14 volumio-new-2026 volumio[1182]: TypeError: Cannot read properties of undefined (reading 'then') Feb 12 12:53:14 volumio-new-2026 volumio[1182]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Feb 12 12:53:14 volumio-new-2026 volumio[1182]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Feb 12 12:53:14 volumio-new-2026 volumio[1182]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Feb 12 12:53:14 volumio-new-2026 volumio[1182]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Feb 12 12:53:14 volumio-new-2026 volumio[1182]: at listOnTimeout (node:internal/timers:573:17) Feb 12 12:53:14 volumio-new-2026 volumio[1182]: at process.processTimers (node:internal/timers:514:7) Feb 12 12:53:14 volumio-new-2026 volumio[1182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 12:53:15 volumio-new-2026 sudo[7925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 12:52' Feb 12 12:53:15 volumio-new-2026 sudo[7925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"