-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Thu 2025-04-24 17:33:08 BST. -- Apr 24 17:32:02 volumio go-librespot[3425]: time="2025-04-24T17:32:02+01:00" level=trace msg="sent dealer ping" Apr 24 17:32:02 volumio go-librespot[3425]: time="2025-04-24T17:32:02+01:00" level=trace msg="received dealer pong" Apr 24 17:32:20 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:20 volumio volumio[977]: info: CorePlayQueue::getTrack 16 Apr 24 17:32:20 volumio volumio[977]: info: Prefetching next song Apr 24 17:32:20 volumio volumio[977]: info: [1745512340087] ControllerSpotify::prefetch Apr 24 17:32:20 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/add_to_queue Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="prefetching next track" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="selected format OGG_VORBIS_320 (ad10b3d8d667a30f9f95f383be125312224f8af3)" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="requested aes key for file ad10b3d8d667a30f9f95f383be125312224f8af3, gid: 4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="fetched first chunk of 17, total size is 8680296 bytes" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=info msg="prefetched track \"Solo\" (duration: 199756ms)" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:20 volumio go-librespot[3425]: time="2025-04-24T17:32:20+01:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:21 volumio go-librespot[3425]: time="2025-04-24T17:32:21+01:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=trace msg="emitting websocket event: not_playing" Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","play_origin":"go-librespot"}} Apr 24 17:32:23 volumio volumio[977]: error: Failed to decode event: not_playing Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","play_origin":"go-librespot"}} Apr 24 17:32:23 volumio volumio[977]: error: Failed to decode event: not_playing Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=trace msg="emitting websocket event: will_play" Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","play_origin":"go-librespot"}} Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=info msg="loaded track \"Solo\" (paused: false, position: 0ms, duration: 199756ms, prefetched: true)" uri="spotify:track:4Skkx52Dh8yo4G1ijAEGs3" Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","play_origin":"go-librespot"}} Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=trace msg="scheduling prefetch in 170s" Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=trace msg="emitting websocket event: metadata" Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","name":"Solo","artist_names":["Myles Smith"],"album_name":"Solo","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","position":0,"duration":199756,"release_date":"year:2023 month:11 day:15","track_number":1,"disc_number":1}} Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","name":"Solo","artist_names":["Myles Smith"],"album_name":"Solo","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","position":0,"duration":199756,"release_date":"year:2023 month:11 day:15","track_number":1,"disc_number":1}} Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:23 volumio go-librespot[3425]: time="2025-04-24T17:32:23+01:00" level=trace msg="emitting websocket event: playing" Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","play_origin":"go-librespot"}} Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: verbose: CURRENT POSITION 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:23 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","play_origin":"go-librespot"}} Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: verbose: CURRENT POSITION 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:23 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: verbose: CURRENT POSITION 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:23 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Solo","artist":"Myles Smith","album":"Solo","albumart":"https://i.scdn.co/image/ab67616d00001e025794dbc9f4b49c6c0264cea2","uri":"spotify:track:4Skkx52Dh8yo4G1ijAEGs3","trackType":"spotify","seek":0,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:23 volumio volumio[977]: verbose: CURRENT POSITION 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:23 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:23 volumio volumio[977]: info: CorePlayQueue::getTrack 15 Apr 24 17:32:23 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:23 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:24 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:32:24 volumio volumio[977]: info: CorePlayQueue::getTrack 16 Apr 24 17:32:25 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:25 volumio volumio[977]: info: CorePlayQueue::getTrack 16 Apr 24 17:32:25 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:25 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:25 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:29 volumio go-librespot[3425]: time="2025-04-24T17:32:29+01:00" level=debug msg="handling skip_next player command from 796abb15504681b44180377575f5b4dd426e910c" Apr 24 17:32:29 volumio go-librespot[3425]: time="2025-04-24T17:32:29+01:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:29 volumio go-librespot[3425]: time="2025-04-24T17:32:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:29 volumio go-librespot[3425]: time="2025-04-24T17:32:29+01:00" level=trace msg="emitting websocket event: will_play" Apr 24 17:32:29 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","play_origin":"go-librespot"}} Apr 24 17:32:29 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","play_origin":"go-librespot"}} Apr 24 17:32:29 volumio go-librespot[3425]: time="2025-04-24T17:32:29+01:00" level=debug msg="selected format OGG_VORBIS_320 (b1aca1741876d6109be7843ac8b4dde1fae959c2)" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:29 volumio go-librespot[3425]: time="2025-04-24T17:32:29+01:00" level=debug msg="requested aes key for file b1aca1741876d6109be7843ac8b4dde1fae959c2, gid: 1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="fetched first chunk of 19, total size is 9589636 bytes" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=info msg="loaded track \"Hide My Gun (Feat. HARDY)\" (paused: true, position: 0ms, duration: 220479ms, prefetched: false)" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=trace msg="emitting websocket event: metadata" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=trace msg="emitting websocket event: stopped" Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","name":"Hide My Gun (Feat. HARDY)","artist_names":["Post Malone","HARDY"],"album_name":"Hide My Gun","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","position":0,"duration":220479,"release_date":"year:2024 month:8 day:15","track_number":1,"disc_number":1}} Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="sending successful reply for dealer request" Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: {"status":"stop","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 16 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 16 Apr 24 17:32:30 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: verbose: CURRENT POSITION 16 Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::play index undefined Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: [1745512350704] ControllerSpotify::clearAddPlayTrack Apr 24 17:32:30 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/play Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","name":"Hide My Gun (Feat. HARDY)","artist_names":["Post Malone","HARDY"],"album_name":"Hide My Gun","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","position":0,"duration":220479,"release_date":"year:2024 month:8 day:15","track_number":1,"disc_number":1}} Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: {"status":"stop","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: info: No code Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:30 volumio volumio[977]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=trace msg="emitting websocket event: paused" Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","play_origin":"go-librespot"}} Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: {"status":"pause","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState stateService pause Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","play_origin":"go-librespot"}} Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: {"status":"pause","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Hide My Gun (Feat. HARDY)","artist":"Post Malone, HARDY","album":"Hide My Gun","albumart":"https://i.scdn.co/image/ab67616d00001e021bffc7c7d16ab8fb88263908","uri":"spotify:track:1UEgWCAt578XMHXBOMnxKx","trackType":"spotify","seek":0,"duration":220,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:30 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState stateService pause Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:32:30 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:30 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:30 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:30 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="resolved context of track" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:30 volumio go-librespot[3425]: time="2025-04-24T17:32:30+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=trace msg="emitting websocket event: will_play" Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","play_origin":"go-librespot"}} Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","play_origin":"go-librespot"}} Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="selected format OGG_VORBIS_320 (131e78ed1b3223d74f19cd02793662bb7dddd9e8)" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="requested aes key for file 131e78ed1b3223d74f19cd02793662bb7dddd9e8, gid: 4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="fetched first chunk of 18, total size is 9087424 bytes" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+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" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=info msg="loaded track \"Leave The Door Open\" (paused: false, position: 0ms, duration: 242096ms, prefetched: false)" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:1UEgWCAt578XMHXBOMnxKx" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=trace msg="scheduling prefetch in 212s" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=trace msg="emitting websocket event: metadata" Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","name":"Leave The Door Open","artist_names":["Bruno Mars","Anderson .Paak","Silk Sonic"],"album_name":"An Evening With Silk Sonic","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","position":0,"duration":242096,"release_date":"year:2021 month:11 day:12","track_number":2,"disc_number":1}} Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","name":"Leave The Door Open","artist_names":["Bruno Mars","Anderson .Paak","Silk Sonic"],"album_name":"An Evening With Silk Sonic","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","position":0,"duration":242096,"release_date":"year:2021 month:11 day:12","track_number":2,"disc_number":1}} Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:31 volumio go-librespot[3425]: time="2025-04-24T17:32:31+01:00" level=trace msg="emitting websocket event: playing" Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","play_origin":"go-librespot"}} Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:31 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:31 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:31 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:31 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:31 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:31 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","play_origin":"go-librespot"}} Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:31 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:31 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:31 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:31 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:31 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:31 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:31 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:31 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:31 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:31 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:31 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:31 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:31 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:31 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:31 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:31 volumio volumio[977]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:32 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:32 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:32 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:32 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:32 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:32 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:32 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:32 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:32 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:32 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:32 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:32 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":0,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:32:32 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:32 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:32 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:32 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:32 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:32 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:32 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:32 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:32 volumio go-librespot[3425]: time="2025-04-24T17:32:32+01:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:32 volumio go-librespot[3425]: time="2025-04-24T17:32:32+01:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:32 volumio go-librespot[3425]: time="2025-04-24T17:32:32+01:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:32 volumio go-librespot[3425]: time="2025-04-24T17:32:32+01:00" level=trace msg="sent dealer ping" Apr 24 17:32:32 volumio go-librespot[3425]: time="2025-04-24T17:32:32+01:00" level=trace msg="received dealer pong" Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=debug msg="handling set_shuffling_context player command from 796abb15504681b44180377575f5b4dd426e910c" Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=debug msg="shuffled context with seed 9934415532585632451 (len: 1, keep: -1)" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=trace msg="emitting websocket event: shuffle_context" Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":true}} Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":8000,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null} Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:39 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:39 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":8000,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null} Apr 24 17:32:39 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:39 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:39 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:39 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: received: {"type":"shuffle_context","data":{"value":true}} Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":8000,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null} Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::servicePushState Apr 24 17:32:39 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:39 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Leave The Door Open","artist":"Bruno Mars, Anderson .Paak, Silk Sonic","album":"An Evening With Silk Sonic","albumart":"https://i.scdn.co/image/ab67616d00001e02d0bbd3ea2ec554f17a6603cc","uri":"spotify:track:4pryE6cN2gFL1FVF5fYINl","trackType":"spotify","seek":8000,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null} Apr 24 17:32:39 volumio volumio[977]: verbose: CURRENT POSITION 17 Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:32:39 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:39 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:39 volumio volumio[977]: info: CoreStateMachine::pushState Apr 24 17:32:39 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:39 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Apr 24 17:32:39 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=debug msg="sending successful reply for dealer request" Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=debug msg="handling set_shuffling_context player command from 796abb15504681b44180377575f5b4dd426e910c" Apr 24 17:32:39 volumio go-librespot[3425]: time="2025-04-24T17:32:39+01:00" level=debug msg="sending successful reply for dealer request" Apr 24 17:32:45 volumio go-librespot[3425]: time="2025-04-24T17:32:45+01:00" level=debug msg="fetched chunk 4/17, size: 524288" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:32:45 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:45 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:32:45 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.192:3000 from 192.168.1.141 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:45 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 24 17:32:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 24 17:32:48 volumio sudo[4700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 17:32:48 volumio sudo[4700]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:32:48 volumio sudo[4700]: pam_unix(sudo:session): session closed for user root Apr 24 17:32:48 volumio sudo[4703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 17:32:48 volumio sudo[4703]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:32:48 volumio sudo[4703]: pam_unix(sudo:session): session closed for user root Apr 24 17:32:48 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.141 UA: Mozilla/5.0 (Linux; Android 14; SM-G991B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 24 17:32:48 volumio sudo[4706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 17:32:48 volumio sudo[4706]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:32:48 volumio sudo[4706]: pam_unix(sudo:session): session closed for user root Apr 24 17:32:48 volumio sudo[4709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 17:32:48 volumio sudo[4709]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:32:48 volumio sudo[4709]: pam_unix(sudo:session): session closed for user root Apr 24 17:32:48 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.141 UA: Mozilla/5.0 (Linux; Android 14; SM-G991B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:48 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 17:32:48 volumio volumio[977]: info: Received Get System Info Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:32:48 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:48 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:48 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:48 volumio volumio[977]: info: Listing playlists Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 24 17:32:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::volumioGetQueue Apr 24 17:32:50 volumio volumio[977]: info: CoreStateMachine::getQueue Apr 24 17:32:50 volumio volumio[977]: info: CorePlayQueue::getQueue Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:32:50 volumio volumio[977]: info: Received Get System Info Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:32:50 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:50 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:32:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 24 17:32:51 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 24 17:32:51 volumio volumio[977]: info: In handleBrowseUri, curUri=spotify Apr 24 17:32:51 volumio volumio[977]: info: New access token = BQCgYqSA4mq-hv-D3ZN0pnR_qq42Y1uO0v1QiRM92ztwxELRGmUKxpZHD64BCPvpREThE2TpveGMw4Rannme7C9Wi7a4VotNgXfmHEL335kxX1zr7rCCFX-S9p_Htk1wVFiqZhr3Gkt5whP1NW75NaQXeD3MyEwPpJsVJao--PxUzU8oUwF9QZI1iLsRmPBtrw-d5K_VdK3VcPdH6-gmHkK5ZTLj_Hpd1DqIsRyX6lGYBUcr7rxyrSwndquiGz2SNqrUWD_b0Fy9gtspHc8XGGqDJZUEC2CE1owTEH172s9ipEnMAg Apr 24 17:32:51 volumio volumio[977]: info: New access token = BQDbbW8E74DhK3wd0K-xykCAlDe24GByfkYc2AP8P-bD4R51A7Ov_Ae6R53FELXJMYVAx3f47m5xw84j1pS_GSkqTgsoEw9yKpGvrqdiRgLkP60tcxhalq-Iw5TvlpSFKJrNmu3r5ouzVh0a4ASPGRt4rCZG4HdqDIZFYDdJo0s07ezWAJnxTkGoHsdboUTDoj9RT49IyxJkO1x76IxnQnf0rHzKppGes0hYbWYXmNKJvy6s_InJQ1N4R6bpbWyZzGTnkV9OLE1QjP7DMJRXou6zTcHtr3w0b_BGRvOsrihxNszCXw Apr 24 17:32:51 volumio volumio[977]: info: New access token = BQANp9Z932uK-LHKboZgdjxwgWtcTilbhyl3J5h2qvhGXUHmqiQzaatN1TRahGp0KCvLXEPOV8Wg7_McJdJVJ5rnivaFpJztEmQlDrLihXVMWLFYQCs8FiD4X7axnQ1i_k8SNdkhQEWO-gBGsAcJlBJN23os_5HubUTBY1gfSovQ6-IMPNDWBAIeaK15i7wTdIti7JhplTHFHbAlmQyEcntHPEeAjsmWd9BcMyIbeQ4GPQB2KRDZs9JrnhIhBTFrAvV5nXApp8-UIJgawW4tOMcgMoB87keo9UD1G6TU-57WV6TM-Q Apr 24 17:32:52 volumio volumio[977]: info: Preload queue cleared Apr 24 17:32:52 volumio volumio[977]: info: Preload queue cleared Apr 24 17:32:52 volumio volumio[977]: info: Preload queue cleared Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:32:56 volumio volumio[977]: info: Received Get System Info Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:32:56 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:56 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:32:56 volumio volumio[977]: info: Received Get System Info Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:32:56 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:56 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:32:56 volumio volumio[977]: info: Received Get System Info Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:32:56 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:32:56 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:32:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:32:58 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 17:32:59 volumio go-librespot[3425]: time="2025-04-24T17:32:59+01:00" level=debug msg="fetched chunk 5/17, size: 524288" uri="spotify:track:4pryE6cN2gFL1FVF5fYINl" Apr 24 17:33:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:33:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:33:00 volumio volumio[977]: info: Discovery: Getting this device information Apr 24 17:33:00 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Apr 24 17:33:00 volumio volumio[977]: info: CorePlayQueue::getTrack 17 Apr 24 17:33:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:33:02 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.192:3000 from 192.168.1.141 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Apr 24 17:33:02 volumio go-librespot[3425]: time="2025-04-24T17:33:02+01:00" level=trace msg="sent dealer ping" Apr 24 17:33:02 volumio go-librespot[3425]: time="2025-04-24T17:33:02+01:00" level=trace msg="received dealer pong" Apr 24 17:33:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Apr 24 17:33:08 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:33:08 volumio volumio[977]: TypeError: Cannot read property 'then' of undefined Apr 24 17:33:08 volumio volumio[977]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Apr 24 17:33:08 volumio volumio[977]: at Socket.emit (events.js:315:20) Apr 24 17:33:08 volumio volumio[977]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 24 17:33:08 volumio volumio[977]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 24 17:33:08 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:33:08 volumio sudo[4796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 17:32 Apr 24 17:33:08 volumio sudo[4796]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"