-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-07-08 20:52:56 CEST. -- Jul 08 20:51:27 volumio go-librespot[8128]: time="2025-07-08T20:51:27+02:00" level=trace msg="sent dealer ping" Jul 08 20:51:27 volumio go-librespot[8128]: time="2025-07-08T20:51:27+02:00" level=trace msg="received dealer pong" Jul 08 20:51:28 volumio volumio[898]: info: CorePlayQueue::getTrack 1 Jul 08 20:51:28 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:28 volumio volumio[898]: info: Prefetching next song Jul 08 20:51:28 volumio volumio[898]: info: [1752000688932] ControllerSpotify::prefetch Jul 08 20:51:28 volumio volumio[898]: info: Sending Spotify command with payload to local API: /player/add_to_queue Jul 08 20:51:29 volumio go-librespot[8128]: time="2025-07-08T20:51:29+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:51:29 volumio go-librespot[8128]: time="2025-07-08T20:51:29+02:00" level=debug msg="prefetching next track" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:29 volumio go-librespot[8128]: time="2025-07-08T20:51:29+02:00" level=debug msg="selected format OGG_VORBIS_320 (c37ec16b54105aacf58c8f1969ab7e3bed747366)" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:29 volumio go-librespot[8128]: time="2025-07-08T20:51:29+02:00" level=debug msg="requested aes key for file c37ec16b54105aacf58c8f1969ab7e3bed747366, gid: 4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:30 volumio go-librespot[8128]: time="2025-07-08T20:51:30+02:00" level=debug msg="fetched first chunk of 17, total size is 8713712 bytes" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:30 volumio go-librespot[8128]: time="2025-07-08T20:51:30+02:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jul 08 20:51:30 volumio go-librespot[8128]: time="2025-07-08T20:51:30+02:00" level=info msg="prefetched track \"Build a Fire\" (duration: 228233ms)" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:31 volumio go-librespot[8128]: time="2025-07-08T20:51:31+02:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:31 volumio go-librespot[8128]: time="2025-07-08T20:51:31+02:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:32 volumio go-librespot[8128]: time="2025-07-08T20:51:32+02:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=trace msg="emitting websocket event: not_playing" Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:0pEsbOUt0KfGByoFQRSzAz","play_origin":"go-librespot"}} Jul 08 20:51:33 volumio volumio[898]: error: Failed to decode event: not_playing Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:0pEsbOUt0KfGByoFQRSzAz","play_origin":"go-librespot"}} Jul 08 20:51:33 volumio volumio[898]: error: Failed to decode event: not_playing Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=trace msg="emitting websocket event: will_play" Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=info msg="loaded track \"Build a Fire\" (paused: false, position: 0ms, duration: 228233ms, prefetched: true)" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=trace msg="scheduling prefetch in 199s" Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=trace msg="emitting websocket event: metadata" Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","name":"Build a Fire","artist_names":["Young Ejecta"],"album_name":"Build a Fire","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","position":0,"duration":228233,"release_date":"year:2017 month:6 day:16","track_number":1,"disc_number":1}} Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","name":"Build a Fire","artist_names":["Young Ejecta"],"album_name":"Build a Fire","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","position":0,"duration":228233,"release_date":"year:2017 month:6 day:16","track_number":1,"disc_number":1}} Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:51:33 volumio go-librespot[8128]: time="2025-07-08T20:51:33+02:00" level=trace msg="emitting websocket event: playing" Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Jul 08 20:51:33 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Jul 08 20:51:33 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Jul 08 20:51:33 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:51:33 volumio volumio[898]: SPOTIFY: {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:51:33 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Jul 08 20:51:33 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:33 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:51:33 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:51:33 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:51:48 volumio go-librespot[8128]: time="2025-07-08T20:51:48+02:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:51:57 volumio go-librespot[8128]: time="2025-07-08T20:51:57+02:00" level=trace msg="received accesspoint ping" Jul 08 20:51:57 volumio go-librespot[8128]: time="2025-07-08T20:51:57+02:00" level=trace msg="received accesspoint pong ack" Jul 08 20:51:57 volumio go-librespot[8128]: time="2025-07-08T20:51:57+02:00" level=trace msg="sent dealer ping" Jul 08 20:51:57 volumio go-librespot[8128]: time="2025-07-08T20:51:57+02:00" level=trace msg="received dealer pong" Jul 08 20:52:01 volumio go-librespot[8128]: time="2025-07-08T20:52:01+02:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:52:15 volumio go-librespot[8128]: time="2025-07-08T20:52:15+02:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:52:27 volumio go-librespot[8128]: time="2025-07-08T20:52:27+02:00" level=trace msg="sent dealer ping" Jul 08 20:52:27 volumio go-librespot[8128]: time="2025-07-08T20:52:27+02:00" level=trace msg="received dealer pong" Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=debug msg="fetched chunk 7/16, size: 524288" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::volumioSeek Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::seek Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: TRACKBLOCK {"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","service":"spop","name":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b27387f3f357adbbd23475b8041a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify","channels":2} Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: Spotify seek to: 35000 Jul 08 20:52:29 volumio volumio[898]: info: Sending Spotify command with payload to local API: /player/seek Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=debug msg="seek track to 35000ms" Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=trace msg="seek to 35000ms (diff: 59ms, samples: 1543500, bytes: 1303710)" uri="spotify:track:4PC2bja9UVkpdzUQBGDwDl" Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=trace msg="scheduling prefetch in 163s" Jul 08 20:52:29 volumio go-librespot[8128]: time="2025-07-08T20:52:29+02:00" level=trace msg="emitting websocket event: seek" Jul 08 20:52:29 volumio volumio[898]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","position":35000,"duration":228233,"play_origin":"go-librespot"}} Jul 08 20:52:29 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:29 volumio volumio[898]: SPOTIFY: {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":35000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":35000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:29 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Jul 08 20:52:29 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:29 volumio volumio[898]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","position":35000,"duration":228233,"play_origin":"go-librespot"}} Jul 08 20:52:29 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:29 volumio volumio[898]: SPOTIFY: {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":35000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":35000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:29 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::syncState stateService play Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play Jul 08 20:52:29 volumio volumio[898]: info: Received an update from plugin. extracting info from payload Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:29 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:29 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::volumioPause Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::pause Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::stPlaybackTimer Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::servicePause Jul 08 20:52:34 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::servicePause Jul 08 20:52:34 volumio volumio[898]: info: Spotify Received pause Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: SPOTIFY PAUSE Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: {"status":"play","position":2,"title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d0000b27387f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","codec":"ogg","seek":35000,"duration":228,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jul 08 20:52:34 volumio volumio[898]: info: Sending Spotify command to local API: /player/pause Jul 08 20:52:34 volumio go-librespot[8128]: time="2025-07-08T20:52:34+02:00" level=debug msg="pause track at 39560ms" Jul 08 20:52:34 volumio go-librespot[8128]: time="2025-07-08T20:52:34+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:52:34 volumio go-librespot[8128]: time="2025-07-08T20:52:34+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:52:34 volumio go-librespot[8128]: time="2025-07-08T20:52:34+02:00" level=trace msg="emitting websocket event: paused" Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: {"status":"pause","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:34 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:34 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:34 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus pause Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:34 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::stPlaybackTimer Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:34 volumio volumio[898]: SPOTIFY: {"status":"pause","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:34 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:34 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:34 volumio volumio[898]: verbose: CURRENT POSITION 2 Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus pause Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:34 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:34 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:34 volumio volumio[898]: info: CoreStateMachine::stPlaybackTimer Jul 08 20:52:41 volumio volumio[898]: info: Clearing queue after UPNP request Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::ClearQueue Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::stop Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::updateTrackBlock Jul 08 20:52:42 volumio volumio[898]: info: CorePlayQueue::getTrackBlock Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::stPlaybackTimer Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:42 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::serviceStop Jul 08 20:52:42 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::serviceStop Jul 08 20:52:42 volumio volumio[898]: info: Spotify Stop Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: SPOTIFY STOP Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: {"status":"pause","position":2,"title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d0000b27387f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","codec":"ogg","seek":39760,"duration":228,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jul 08 20:52:42 volumio volumio[898]: info: Sending Spotify command to local API: /player/pause Jul 08 20:52:42 volumio volumio[898]: info: CorePlayQueue::clearPlayQueue Jul 08 20:52:42 volumio volumio[898]: info: CorePlayQueue::saveQueue Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:52:42 volumio go-librespot[8128]: time="2025-07-08T20:52:42+02:00" level=debug msg="pause track at 39980ms" Jul 08 20:52:42 volumio volumio[898]: info: Starting UPNP Playback Jul 08 20:52:42 volumio volumio[898]: info: Preparing playback through UPNP Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Jul 08 20:52:42 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:42 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 20:52:42 volumio go-librespot[8128]: time="2025-07-08T20:52:42+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:52:42 volumio go-librespot[8128]: time="2025-07-08T20:52:42+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:52:42 volumio go-librespot[8128]: time="2025-07-08T20:52:42+02:00" level=trace msg="emitting websocket event: paused" Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: {"status":"pause","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:42 volumio volumio[898]: verbose: In UPNP mode Jul 08 20:52:42 volumio volumio[898]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","play_origin":"go-librespot"}} Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:42 volumio volumio[898]: SPOTIFY: {"status":"pause","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:42 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:42 volumio volumio[898]: verbose: In UPNP mode Jul 08 20:52:42 volumio volumio[898]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Jul 08 20:52:42 volumio volumio[898]: info: Clearing queue after UPNP request Jul 08 20:52:43 volumio volumio[898]: info: CoreStateMachine::ClearQueue Jul 08 20:52:43 volumio volumio[898]: info: CoreStateMachine::stop Jul 08 20:52:43 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:52:43 volumio volumio[898]: info: CorePlayQueue::clearPlayQueue Jul 08 20:52:43 volumio volumio[898]: info: CorePlayQueue::saveQueue Jul 08 20:52:43 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Jul 08 20:52:43 volumio volumio[898]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:52:43 volumio volumio[898]: info: Starting UPNP Playback Jul 08 20:52:43 volumio volumio[898]: info: Preparing playback through UPNP Jul 08 20:52:43 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Jul 08 20:52:43 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:43 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=info msg="playback was transferred to " Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=trace msg="closed output device because of stop command" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="put connect state inactive" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=trace msg="emitting websocket event: inactive" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=trace msg="emitting websocket event: stopped" Jul 08 20:52:55 volumio volumio[898]: SPOTIFY: received: {"type":"inactive","data":null} Jul 08 20:52:55 volumio volumio[898]: error: Failed to decode event: inactive Jul 08 20:52:55 volumio volumio[898]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Jul 08 20:52:55 volumio volumio[898]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 20:52:55 volumio volumio[898]: SPOTIFY: {"status":"stop","service":"spop","title":"Build a Fire","artist":"Young Ejecta","album":"Build a Fire","albumart":"https://i.scdn.co/image/ab67616d00001e0287f3f357adbbd23475b8041a","uri":"spotify:track:4PC2bja9UVkpdzUQBGDwDl","trackType":"spotify","seek":40000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 20:52:55 volumio volumio[898]: info: CoreCommandRouter::servicePushState Jul 08 20:52:55 volumio volumio[898]: info: CoreStateMachine::pushState Jul 08 20:52:55 volumio volumio[898]: info: CorePlayQueue::getTrack 2 Jul 08 20:52:55 volumio volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 20:52:55 volumio volumio[898]: TypeError: Cannot read property 'name' of undefined Jul 08 20:52:55 volumio volumio[898]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 08 20:52:55 volumio volumio[898]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:527:20) Jul 08 20:52:55 volumio volumio[898]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:597:10) Jul 08 20:52:55 volumio volumio[898]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Jul 08 20:52:55 volumio volumio[898]: at ControllerSpotify.pushState (/data/plugins/music_service/spop/index.js:433:31) Jul 08 20:52:55 volumio volumio[898]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:323:14) Jul 08 20:52:55 volumio volumio[898]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Jul 08 20:52:55 volumio volumio[898]: at WebSocket.emit (events.js:315:20) Jul 08 20:52:55 volumio volumio[898]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jul 08 20:52:55 volumio volumio[898]: at Receiver.emit (events.js:315:20) Jul 08 20:52:55 volumio volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="dealer connection closed" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="dealer recv loop stopped" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="obtained new client token: AABWYJkyomyiCHqt8HscvBwsxXHj4fOdaQchJ355NH1e7NTeFurMTMPSIp3m/5L+LhF+GKI1k6WofQDzQYhPB6y6pYn17qanzo6Tsc8f/PMI9U5OR22/fpSRTD619ZEROZ7w/s8SPNddC14wqvm2kCURbsG3bw7vXZZnCRfrjS51H6gGLjRMIF9w6ljGgGA3fyGm1EsCQM9F5W59y0tvDhWzslkzoI55I6+JcwCTlVod6ZFeEgm8cVCVRjI=" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="completed keyexchange" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="completed challenge" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=info msg="authenticated AP as 21zy3lszge55xajfhifhjqlky" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=info msg="authenticated Login5 as 21zy3lszge55xajfhifhjqlky" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="restored session after logout, username: 21zy3lszge55xajfhifhjqlky" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="dealer connection opened" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=trace msg="starting accesspoint recv loop" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=trace msg="starting dealer recv loop" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=trace msg="received accesspoint ping" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 08 20:52:55 volumio go-librespot[8128]: time="2025-07-08T20:52:55+02:00" level=debug msg="received connection id: MjJjMzdkOTgtOTMxNC00ZjM4LWI5OWQtZjhkYmU5ZGJlMjlmK2RlYWxlcit0Y3A6Ly8wYWIxNTFlYi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQjkyOTdDMUNCQjg3QzlGRjNGQ0ZCNDBGNUE1RTAxQkFDQ0ExNTg3QzAwRTcyQzA0RUVEQzcyMDJBRTZGODk0Nw==" Jul 08 20:52:56 volumio go-librespot[8128]: time="2025-07-08T20:52:56+02:00" level=trace msg="received accesspoint pong ack" Jul 08 20:52:56 volumio go-librespot[8128]: time="2025-07-08T20:52:56+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 08 20:52:56 volumio go-librespot[8128]: time="2025-07-08T20:52:56+02:00" level=debug msg="update volume to 65535/65535" Jul 08 20:52:56 volumio sudo[9970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 20:51 Jul 08 20:52:56 volumio sudo[9970]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"