Mar 24 15:04:01 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:02 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:05 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateAmpType [object Object] Mar 24 15:04:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateAmpType Mar 24 15:04:05 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:04:06 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:06 volumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 24 15:04:06 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState Mar 24 15:04:06 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:06 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:07 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:10 volumio go-librespot[1763]: time="2026-03-24T15:04:10+01:00" level=trace msg="sent dealer ping" Mar 24 15:04:10 volumio go-librespot[1763]: time="2026-03-24T15:04:10+01:00" level=trace msg="received dealer pong" Mar 24 15:04:11 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:11 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:12 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:16 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:16 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:17 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:21 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:21 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:22 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:23 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateAmpSettings [object Object] Mar 24 15:04:23 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateAmpSettings Mar 24 15:04:23 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:04:26 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:26 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:27 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:28 volumio volumio[1173]: info: CoreCommandRouter::volumioPause Mar 24 15:04:28 volumio volumio[1173]: info: CoreStateMachine::pause Mar 24 15:04:28 volumio volumio[1173]: info: CoreStateMachine::stPlaybackTimer Mar 24 15:04:28 volumio volumio[1173]: info: CoreStateMachine::servicePause Mar 24 15:04:28 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:28 volumio volumio[1173]: info: CoreCommandRouter::servicePause Mar 24 15:04:28 volumio volumio[1173]: info: Spotify Received pause Mar 24 15:04:28 volumio volumio[1173]: SPOTIFY: SPOTIFY PAUSE Mar 24 15:04:28 volumio volumio[1173]: SPOTIFY: {"status":"play","position":0,"title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d0000b2732613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","codec":"ogg","seek":85000,"duration":182,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"92","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Mar 24 15:04:28 volumio volumio[1173]: info: Sending Spotify command to local API: /player/pause Mar 24 15:04:28 volumio go-librespot[1763]: time="2026-03-24T15:04:28+01:00" level=debug msg="pause track at 170971ms" Mar 24 15:04:28 volumio go-librespot[1763]: time="2026-03-24T15:04:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:29 volumio go-librespot[1763]: time="2026-03-24T15:04:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:29 volumio go-librespot[1763]: time="2026-03-24T15:04:29+01:00" level=trace msg="emitting websocket event: paused" Mar 24 15:04:29 volumio volumio[1173]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","play_origin":"go-librespot"}} Mar 24 15:04:29 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:29 volumio volumio[1173]: SPOTIFY: {"status":"pause","service":"spop","title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d00001e022613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","seek":172000,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:29 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:29 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:29 volumio volumio[1173]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d00001e022613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","seek":172000,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:29 volumio volumio[1173]: verbose: CURRENT POSITION 0 Mar 24 15:04:29 volumio volumio[1173]: info: CoreStateMachine::syncState stateService pause Mar 24 15:04:29 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus pause Mar 24 15:04:29 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:29 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:29 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:29 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:29 volumio volumio[1173]: info: CoreStateMachine::stPlaybackTimer Mar 24 15:04:29 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:29 volumio volumio[1173]: info: camilladsp respawn in 100 ms (attempt 1/10) Mar 24 15:04:29 volumio volumio[1173]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 24 15:04:30 volumio volumio[1173]: info: CoreCommandRouter::volumioPlay Mar 24 15:04:30 volumio volumio[1173]: info: CoreStateMachine::play index undefined Mar 24 15:04:30 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:04:30 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:30 volumio volumio[1173]: info: CoreStateMachine::startPlaybackTimer Mar 24 15:04:30 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:30 volumio volumio[1173]: info: Spotify Resume Mar 24 15:04:30 volumio volumio[1173]: info: Sending Spotify command to local API: /player/resume Mar 24 15:04:30 volumio go-librespot[1763]: time="2026-03-24T15:04:30+01:00" level=trace msg="seek to 170971ms (diff: 132ms, samples: 7539821, bytes: 7135293)" uri="spotify:track:62cD87SVSewf9mX6D7hHXr" Mar 24 15:04:30 volumio go-librespot[1763]: time="2026-03-24T15:04:30+01: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" Mar 24 15:04:30 volumio go-librespot[1763]: time="2026-03-24T15:04:30+01:00" level=debug msg="resume track at 170839ms" Mar 24 15:04:31 volumio volumio[1173]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 24 15:04:31 volumio volumio[1173]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 24 15:04:31 volumio go-librespot[1763]: time="2026-03-24T15:04:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:31 volumio go-librespot[1763]: time="2026-03-24T15:04:31+01:00" level=trace msg="prefetch as soon as possible" Mar 24 15:04:31 volumio volumio[1173]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 15:04:31 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:31 volumio go-librespot[1763]: time="2026-03-24T15:04:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:31 volumio go-librespot[1763]: time="2026-03-24T15:04:31+01:00" level=trace msg="emitting websocket event: playing" Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","resume":true,"play_origin":"go-librespot"}} Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d00001e022613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","seek":172000,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:31 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:31 volumio volumio[1173]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d00001e022613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","seek":172000,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:31 volumio volumio[1173]: verbose: CURRENT POSITION 0 Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::syncState stateService play Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus pause Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:31 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:31 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video6!": Error [ERR_STREAM_DESTROYED]: Cannot call write after a stream was destroyed Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d00001e022613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","seek":172000,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:31 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:31 volumio volumio[1173]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Selfish","artist":"Asia Cruise","album":"Selfish (Main Version)","albumart":"https://i.scdn.co/image/ab67616d00001e022613f2d142cae80b7297375f","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","trackType":"spotify","seek":172000,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:31 volumio volumio[1173]: verbose: CURRENT POSITION 0 Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::syncState stateService play Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus play Mar 24 15:04:31 volumio volumio[1173]: info: Received an update from plugin. extracting info from payload Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:31 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:31 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:31 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:31 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:31 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:32 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:32 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video6!": Error [ERR_STREAM_DESTROYED]: Cannot call write after a stream was destroyed Mar 24 15:04:32 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video6!": Error [ERR_STREAM_DESTROYED]: Cannot call write after a stream was destroyed Mar 24 15:04:32 volumio volumio[1173]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 24 15:04:32 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:32 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:36 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:36 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 Mar 24 15:04:36 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:36 volumio volumio[1173]: info: Prefetching next song Mar 24 15:04:36 volumio volumio[1173]: info: [1774361076831] ControllerSpotify::prefetch Mar 24 15:04:36 volumio volumio[1173]: info: Sending Spotify command with payload to local API: /player/add_to_queue Mar 24 15:04:37 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=trace msg="prefetch as soon as possible" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="prefetching next track" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="selected format OGG_VORBIS_320 (847b3ff0256a65af202f33d034b949eb38071a50)" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="requested aes key for file 847b3ff0256a65af202f33d034b949eb38071a50, gid: 2pwED7E7gGr3UR7T3s3LSM" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="fetched first chunk of 16, total size is 8112885 bytes" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=info msg="prefetched track \"Papaoutai - Afro Soul\" (duration: 279962ms)" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio go-librespot[1763]: time="2026-03-24T15:04:37+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:37 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:40 volumio go-librespot[1763]: time="2026-03-24T15:04:40+01:00" level=trace msg="sent dealer ping" Mar 24 15:04:40 volumio go-librespot[1763]: time="2026-03-24T15:04:40+01:00" level=trace msg="received dealer pong" Mar 24 15:04:40 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateAmpSettings [object Object] Mar 24 15:04:40 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateAmpSettings Mar 24 15:04:40 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=trace msg="emitting websocket event: not_playing" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio volumio[1173]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","play_origin":"go-librespot"}} Mar 24 15:04:41 volumio volumio[1173]: error: Failed to decode event: not_playing Mar 24 15:04:41 volumio volumio[1173]: info: CoreStateMachine::startPlaybackTimer Mar 24 15:04:41 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:41 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=trace msg="emitting websocket event: will_play" Mar 24 15:04:41 volumio volumio[1173]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:2xVyfHsYSEz0IcE3G9czCS","play_origin":"go-librespot"}} Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="selected format OGG_VORBIS_320 (847b3ff0256a65af202f33d034b949eb38071a50)" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="requested aes key for file 847b3ff0256a65af202f33d034b949eb38071a50, gid: 2pwED7E7gGr3UR7T3s3LSM" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="fetched first chunk of 16, total size is 8112885 bytes" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=info msg="loaded track \"Papaoutai - Afro Soul\" (paused: false, position: 0ms, duration: 279962ms, prefetched: false)" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:41 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:41 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:41 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:41 volumio go-librespot[1763]: time="2026-03-24T15:04:41+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:42 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error [ERR_STREAM_DESTROYED]: Cannot call write after a stream was destroyed Mar 24 15:04:42 volumio go-librespot[1763]: time="2026-03-24T15:04:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:42 volumio go-librespot[1763]: time="2026-03-24T15:04:42+01:00" level=trace msg="scheduling prefetch in 250s" Mar 24 15:04:42 volumio go-librespot[1763]: time="2026-03-24T15:04:42+01:00" level=trace msg="emitting websocket event: metadata" Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","name":"Papaoutai - Afro Soul","artist_names":["Chill77"],"album_name":"Papaoutai (Afro Soul)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","position":0,"duration":279962,"release_date":"year:2025 month:12 day:21","track_number":1,"disc_number":1}} Mar 24 15:04:42 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:42 volumio go-librespot[1763]: time="2026-03-24T15:04:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:42 volumio go-librespot[1763]: time="2026-03-24T15:04:42+01:00" level=trace msg="emitting websocket event: playing" Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:2xVyfHsYSEz0IcE3G9czCS","resume":false,"play_origin":"go-librespot"}} Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":0,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:42 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:42 volumio volumio[1173]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":0,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:42 volumio volumio[1173]: verbose: CURRENT POSITION 1 Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::syncState stateService play Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus play Mar 24 15:04:42 volumio volumio[1173]: info: Received an update from plugin. extracting info from payload Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:42 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:42 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:42 volumio volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":1000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:42 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:42 volumio volumio[1173]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":1000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:42 volumio volumio[1173]: verbose: CURRENT POSITION 1 Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::syncState stateService play Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus play Mar 24 15:04:42 volumio volumio[1173]: info: Received an update from plugin. extracting info from payload Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:42 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:42 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:42 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:42 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:43 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:43 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:43 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:43 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:43 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:43 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error [ERR_SOCKET_CLOSED_BEFORE_CONNECTION]: Socket closed before the connection was established Mar 24 15:04:43 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error: connect ECONNREFUSED 192.168.0.162:9596 Mar 24 15:04:43 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error: connect ECONNREFUSED 192.168.0.162:9596 Mar 24 15:04:43 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error: connect ECONNREFUSED 192.168.0.162:9596 Mar 24 15:04:46 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:47 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:48 volumio volumio[1173]: info: CoreCommandRouter::volumioPause Mar 24 15:04:48 volumio volumio[1173]: info: CoreStateMachine::pause Mar 24 15:04:48 volumio volumio[1173]: info: CoreStateMachine::stPlaybackTimer Mar 24 15:04:48 volumio volumio[1173]: info: CoreStateMachine::servicePause Mar 24 15:04:48 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:48 volumio volumio[1173]: info: CoreCommandRouter::servicePause Mar 24 15:04:48 volumio volumio[1173]: info: Spotify Received pause Mar 24 15:04:48 volumio volumio[1173]: SPOTIFY: SPOTIFY PAUSE Mar 24 15:04:48 volumio volumio[1173]: SPOTIFY: {"status":"play","position":1,"title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d0000b27393e32f3600f695620a7e0216","uri":"spotify:track:2xVyfHsYSEz0IcE3G9czCS","trackType":"spotify","codec":"ogg","seek":1000,"duration":279,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"92","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Mar 24 15:04:48 volumio volumio[1173]: info: Sending Spotify command to local API: /player/pause Mar 24 15:04:48 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:48 volumio go-librespot[1763]: time="2026-03-24T15:04:48+01:00" level=debug msg="pause track at 6172ms" Mar 24 15:04:49 volumio volumio[1173]: info: camilladsp respawn in 200 ms (attempt 2/10) Mar 24 15:04:49 volumio go-librespot[1763]: time="2026-03-24T15:04:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:49 volumio go-librespot[1763]: time="2026-03-24T15:04:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:49 volumio go-librespot[1763]: time="2026-03-24T15:04:49+01:00" level=trace msg="emitting websocket event: paused" Mar 24 15:04:49 volumio volumio[1173]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:2xVyfHsYSEz0IcE3G9czCS","play_origin":"go-librespot"}} Mar 24 15:04:49 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:49 volumio volumio[1173]: SPOTIFY: {"status":"pause","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":7000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:49 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:49 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:49 volumio volumio[1173]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":7000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:49 volumio volumio[1173]: verbose: CURRENT POSITION 1 Mar 24 15:04:49 volumio volumio[1173]: info: CoreStateMachine::syncState stateService pause Mar 24 15:04:49 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus pause Mar 24 15:04:49 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:49 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:49 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:49 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:49 volumio volumio[1173]: info: CoreStateMachine::stPlaybackTimer Mar 24 15:04:49 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:49 volumio volumio[1173]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 24 15:04:50 volumio volumio[1173]: info: CoreCommandRouter::volumioPlay Mar 24 15:04:50 volumio volumio[1173]: info: CoreStateMachine::play index undefined Mar 24 15:04:50 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 15:04:50 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:50 volumio volumio[1173]: info: CoreStateMachine::startPlaybackTimer Mar 24 15:04:50 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:50 volumio volumio[1173]: info: Spotify Resume Mar 24 15:04:50 volumio volumio[1173]: info: Sending Spotify command to local API: /player/resume Mar 24 15:04:50 volumio go-librespot[1763]: time="2026-03-24T15:04:50+01:00" level=trace msg="seek to 6172ms (diff: 125ms, samples: 272185, bytes: 172819)" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:04:50 volumio go-librespot[1763]: time="2026-03-24T15:04:50+01: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" Mar 24 15:04:50 volumio go-librespot[1763]: time="2026-03-24T15:04:50+01:00" level=debug msg="resume track at 5673ms" Mar 24 15:04:50 volumio volumio[1173]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 24 15:04:50 volumio volumio[1173]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 24 15:04:51 volumio go-librespot[1763]: time="2026-03-24T15:04:51+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:51 volumio go-librespot[1763]: time="2026-03-24T15:04:51+01:00" level=trace msg="scheduling prefetch in 243s" Mar 24 15:04:51 volumio volumio[1173]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 15:04:51 volumio go-librespot[1763]: time="2026-03-24T15:04:51+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 24 15:04:51 volumio go-librespot[1763]: time="2026-03-24T15:04:51+01:00" level=trace msg="emitting websocket event: playing" Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:62cD87SVSewf9mX6D7hHXr","uri":"spotify:track:2xVyfHsYSEz0IcE3G9czCS","resume":true,"play_origin":"go-librespot"}} Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":7000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:51 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:51 volumio volumio[1173]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":7000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:51 volumio volumio[1173]: verbose: CURRENT POSITION 1 Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::syncState stateService play Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus pause Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:51 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:51 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:51 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error [ERR_SOCKET_CLOSED_BEFORE_CONNECTION]: Socket closed before the connection was established Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":7000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::servicePushState Mar 24 15:04:51 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:51 volumio volumio[1173]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Papaoutai - Afro Soul","artist":"Chill77","album":"Papaoutai (Afro Soul)","albumart":"https://i.scdn.co/image/ab67616d00001e0293e32f3600f695620a7e0216","uri":"spotify:track:2pwED7E7gGr3UR7T3s3LSM","trackType":"spotify","seek":7000,"duration":279,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 24 15:04:51 volumio volumio[1173]: verbose: CURRENT POSITION 1 Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::syncState stateService play Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus play Mar 24 15:04:51 volumio volumio[1173]: info: Received an update from plugin. extracting info from payload Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:51 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:51 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:04:51 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:04:51 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:51 volumio volumio[1173]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Mar 24 15:04:51 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error [ERR_STREAM_DESTROYED]: Cannot call write after a stream was destroyed Mar 24 15:04:51 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] sendCommand: Could not send command to serial interface "video1!": Error [ERR_STREAM_DESTROYED]: Cannot call write after a stream was destroyed Mar 24 15:04:51 volumio volumio[1173]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 24 15:04:52 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:53 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:56 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:57 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:04:58 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:05:00 volumio go-librespot[1763]: time="2026-03-24T15:05:00+01:00" level=debug msg="fetched chunk 4/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:05:01 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:05:02 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:05:03 volumio volumio[1173]: error: [SERIALAMPCONTROLLER] onTcpError: Connection refused, trying to reconnect in 5 seconds. Mar 24 15:05:06 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateSerialSettings [object Object] Mar 24 15:05:06 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateSerialSettings Mar 24 15:05:06 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:05:10 volumio go-librespot[1763]: time="2026-03-24T15:05:10+01:00" level=trace msg="received accesspoint ping" Mar 24 15:05:10 volumio go-librespot[1763]: time="2026-03-24T15:05:10+01:00" level=trace msg="received accesspoint pong ack" Mar 24 15:05:10 volumio go-librespot[1763]: time="2026-03-24T15:05:10+01:00" level=trace msg="sent dealer ping" Mar 24 15:05:10 volumio go-librespot[1763]: time="2026-03-24T15:05:10+01:00" level=trace msg="received dealer pong" Mar 24 15:05:16 volumio go-librespot[1763]: time="2026-03-24T15:05:16+01:00" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:05:26 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateAmpType [object Object] Mar 24 15:05:26 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateAmpType Mar 24 15:05:26 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:05:26 volumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 24 15:05:26 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState Mar 24 15:05:26 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:05:33 volumio go-librespot[1763]: time="2026-03-24T15:05:33+01:00" level=debug msg="fetched chunk 6/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:05:38 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateAmpType [object Object] Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateAmpType Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 24 15:05:38 volumio volumio[1173]: info: Updating Volume Controller Parameters: Device: 2 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 24 15:05:38 volumio volumio[1173]: info: Disabling external Volume Control Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:05:38 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:05:38 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:05:38 volumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 24 15:05:38 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState Mar 24 15:05:38 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:05:40 volumio go-librespot[1763]: time="2026-03-24T15:05:40+01:00" level=trace msg="sent dealer ping" Mar 24 15:05:40 volumio go-librespot[1763]: time="2026-03-24T15:05:40+01:00" level=trace msg="received dealer pong" Mar 24 15:05:50 volumio go-librespot[1763]: time="2026-03-24T15:05:50+01:00" level=debug msg="fetched chunk 7/15, size: 524288" uri="spotify:track:2xVyfHsYSEz0IcE3G9czCS" Mar 24 15:05:52 volumio volumio[1173]: info: CALLMETHOD: system_hardware serialampcontroller updateAmpType [object Object] Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: serialampcontroller , updateAmpType Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 24 15:05:52 volumio volumio[1173]: info: Updating Volume Controller Parameters: Device: 2 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 24 15:05:52 volumio volumio[1173]: info: Disabling external Volume Control Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 15:05:52 volumio volumio[1173]: info: CoreStateMachine::pushState Mar 24 15:05:52 volumio volumio[1173]: info: CorePlayQueue::getTrack 1 Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 15:05:52 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState Mar 24 15:05:52 volumio volumio[1173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 15:05:52 volumio volumio[1173]: TypeError [ERR_INVALID_ARG_TYPE]: The "chunk" argument must be of type string or an instance of Buffer or Uint8Array. Received undefined Mar 24 15:05:52 volumio volumio[1173]: at new NodeError (node:internal/errors:405:5) Mar 24 15:05:52 volumio volumio[1173]: at _write (node:internal/streams/writable:322:13) Mar 24 15:05:52 volumio volumio[1173]: at Writable.write (node:internal/streams/writable:344:10) Mar 24 15:05:52 volumio volumio[1173]: at SerialPort.write (/data/plugins/system_hardware/serialampcontroller/node_modules/@serialport/stream/dist/index.js:152:22) Mar 24 15:05:52 volumio volumio[1173]: at serialampcontroller.sendStatusRequest (/data/plugins/system_hardware/serialampcontroller/index.js:1067:19) Mar 24 15:05:52 volumio volumio[1173]: at serialampcontroller.getAmpStatus (/data/plugins/system_hardware/serialampcontroller/index.js:299:14) Mar 24 15:05:52 volumio volumio[1173]: at SerialPort. (/data/plugins/system_hardware/serialampcontroller/index.js:621:26) Mar 24 15:05:52 volumio volumio[1173]: at SerialPort.emit (node:events:514:28) Mar 24 15:05:52 volumio volumio[1173]: at settings.binding.open.then.opening (/data/plugins/system_hardware/serialampcontroller/node_modules/@serialport/stream/dist/index.js:111:18) { Mar 24 15:05:52 volumio volumio[1173]: code: 'ERR_INVALID_ARG_TYPE' Mar 24 15:05:52 volumio volumio[1173]: } Mar 24 15:05:52 volumio volumio[1173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 15:05:53 volumio sudo[2725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 15:04' Mar 24 15:05:53 volumio sudo[2725]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"