Some tracks do not play

It seems like some tracks cannot be played on go-librespot, but work on the original client. I have not found any myself. See https://community.volumio.com/t/new-2023-spotify-plugin/63381/348

hi devgianlu, Hire you are the log, when next stop:

Aug 01 14:36:25 main volumio[824]: info: CorePlayQueue::getTrack 473 Aug 01 14:36:25 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:25 main volumio[824]: info: Prefetching next song Aug 01 14:36:28 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","play_origin":"go-librespot"}} Aug 01 14:36:28 main volumio[824]: error: Failed to decode event: not_playing Aug 01 14:36:28 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","play_origin":"go-librespot"}} Aug 01 14:36:28 main volumio[824]: error: Failed to decode event: not_playing Aug 01 14:36:28 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","play_origin":"go-librespot"}} Aug 01 14:36:28 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","name":"Cheek To Cheek","artist_names":["Pearl Django"],"album_name":"Modern Times","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","position":0,"duration":257946,"release_date":"year:2007 month:7 day:5","track_number":13,"disc_number":1}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 473 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 473 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 473 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::play index undefined Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::startPlaybackTimer Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: [1722512189679] ControllerSpotify::clearAddPlayTrack Aug 01 14:36:29 main volumio[824]: info: Sending Spotify command with payload to local API: /player/play Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","name":"Cheek To Cheek","artist_names":["Pearl Django"],"album_name":"Modern Times","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","position":0,"duration":257946,"release_date":"year:2007 month:7 day:5","track_number":13,"disc_number":1}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: No code Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: No code Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: No code Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: No code Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:29 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Cheek To Cheek","artist":"Pearl Django","album":"Modern Times","albumart":"https://i.scdn.co/image/ab67616d00001e02a8ae4bc3692c8e037152ca35","uri":"spotify:track:2jAoRLNUgGh1ZXmMShTdXp","trackType":"spotify","seek":0,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:29 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: info: No code Aug 01 14:36:29 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:29 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:29 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:29 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:30 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","name":"The Man I Love","artist_names":["The Hot Club Of San Francisco"],"album_name":"Swing This","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","position":0,"duration":256066,"release_date":"year:1997","track_number":5,"disc_number":1}} Aug 01 14:36:30 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:30 main volumio[824]: error: Failed to decode event: not_playing Aug 01 14:36:30 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","name":"The Man I Love","artist_names":["The Hot Club Of San Francisco"],"album_name":"Swing This","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","position":0,"duration":256066,"release_date":"year:1997","track_number":5,"disc_number":1}} Aug 01 14:36:30 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:30 main volumio[824]: error: Failed to decode event: not_playing Aug 01 14:36:30 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:30 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","name":"The Man I Love","artist_names":["The Hot Club Of San Francisco"],"album_name":"Swing This","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","position":0,"duration":256066,"release_date":"year:1997","track_number":5,"disc_number":1}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: info: No code Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","name":"The Man I Love","artist_names":["The Hot Club Of San Francisco"],"album_name":"Swing This","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","position":0,"duration":256066,"release_date":"year:1997","track_number":5,"disc_number":1}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: info: No code Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 14:36:31 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:36:31 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 14:36:31 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 14:36:31 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 14:36:31 main volumio[824]: info: CoreStateMachine::pushState Aug 01 14:36:31 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:36:31 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 14:36:31 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 14:40:43 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:40:43 main volumio[824]: info: CorePlayQueue::getTrack 475 Aug 01 14:40:43 main volumio[824]: info: Prefetching next song Aug 01 14:48:13 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 14:48:13 main volumio[824]: info: Received Get System Info Aug 01 14:48:13 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 14:48:13 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 14:48:13 main volumio[824]: info: Discovery: Getting this device information Aug 01 14:48:13 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 14:48:13 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 14:48:13 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 15:51:50 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 15:51:50 main volumio[824]: info: Received Get System Info Aug 01 15:51:50 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 15:51:50 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 15:51:50 main volumio[824]: info: Discovery: Getting this device information Aug 01 15:51:50 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 15:51:50 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 15:51:50 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 16:53:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 16:53:09 main volumio[824]: info: Received Get System Info Aug 01 16:53:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 16:53:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 16:53:09 main volumio[824]: info: Discovery: Getting this device information Aug 01 16:53:09 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 16:53:09 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 16:53:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 17:00:08 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 01 17:00:08 main volumio-remote-updater[594]: No test mode Aug 01 17:00:08 main volumio-remote-updater[594]: No alpha test mode Aug 01 17:00:08 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 01 17:00:08 main volumio[824]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 01 17:00:08 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 01 17:55:33 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 17:55:33 main volumio[824]: info: Received Get System Info Aug 01 17:55:33 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 17:55:33 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 17:55:33 main volumio[824]: info: Discovery: Getting this device information Aug 01 17:55:33 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 17:55:33 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 17:55:33 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 18:57:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 18:57:57 main volumio[824]: info: Received Get System Info Aug 01 18:57:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 18:57:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 18:57:57 main volumio[824]: info: Discovery: Getting this device information Aug 01 18:57:57 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 18:57:57 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 18:57:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 19:59:28 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 19:59:28 main volumio[824]: info: Received Get System Info Aug 01 19:59:28 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 19:59:28 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 19:59:28 main volumio[824]: info: Discovery: Getting this device information Aug 01 19:59:28 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 19:59:28 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 19:59:28 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 20:59:41 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 01 20:59:41 main volumio[824]: info: Received Get System Info Aug 01 20:59:41 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 01 20:59:41 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 01 20:59:41 main volumio[824]: info: Discovery: Getting this device information Aug 01 20:59:41 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 20:59:41 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 20:59:41 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 21:12:10 main sshd[32046]: Accepted password for xxx from 192.168.xxx.xxx port 57498 ssh2 Aug 01 21:12:10 main sshd[32046]: pam_unix(sshd:session): session opened for user xxx by (uid=0) Aug 01 21:12:10 main systemd-logind[592]: New session 6 of user xxx. Aug 01 21:12:10 main systemd[32049]: pam_unix(systemd-user:session): session opened for user xxx by (uid=0) Aug 01 21:12:10 main systemd[1]: Started Session 6 of user xxx.

and how its look when first access to web browser, and after reload the browser: first opening web refresh - Volumio stop play 402 minutes ago

When I click the "Play" button, the track starts playing:

Aug 01 21:21:19 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 01 21:37:39 main volumio[824]: info: CoreCommandRouter::volumioGetQueue Aug 01 21:37:39 main volumio[824]: info: CoreStateMachine::getQueue Aug 01 21:37:39 main volumio[824]: info: CorePlayQueue::getQueue Aug 01 21:37:42 main volumio[824]: info: CoreCommandRouter::volumioPlay Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::play index 474 Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::stop Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::stPlaybackTimer Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::updateTrackBlock Aug 01 21:37:42 main volumio[824]: info: CorePlayQueue::getTrackBlock Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:42 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:42 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 21:37:42 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::serviceStop Aug 01 21:37:42 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:42 main volumio[824]: info: CoreCommandRouter::serviceStop Aug 01 21:37:42 main volumio[824]: info: Spotify Stop Aug 01 21:37:42 main volumio[824]: SPOTIFY: SPOTIFY STOP Aug 01 21:37:42 main volumio[824]: SPOTIFY: {"status":"play","position":474,"title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d0000b273f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","codec":"ogg","seek":297,"duration":256,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":60,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Aug 01 21:37:42 main volumio[824]: info: Sending Spotify command to local API: /player/pause Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::play index undefined Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 01 21:37:42 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:42 main volumio[824]: info: CoreStateMachine::startPlaybackTimer Aug 01 21:37:42 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:42 main volumio[824]: info: [1722537462864] ControllerSpotify::clearAddPlayTrack Aug 01 21:37:42 main volumio[824]: info: Sending Spotify command with payload to local API: /player/play Aug 01 21:37:42 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:43 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 21:37:43 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","name":"The Man I Love","artist_names":["The Hot Club Of San Francisco"],"album_name":"Swing This","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","position":0,"duration":256066,"release_date":"year:1997","track_number":5,"disc_number":1}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","name":"The Man I Love","artist_names":["The Hot Club Of San Francisco"],"album_name":"Swing This","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","position":0,"duration":256066,"release_date":"year:1997","track_number":5,"disc_number":1}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 21:37:44 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 21:37:44 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 21:37:44 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 01 21:37:44 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","play_origin":"go-librespot"}} Aug 01 21:37:44 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 21:37:44 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 21:37:44 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 21:37:44 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 21:37:44 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 01 21:37:44 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Man I Love","artist":"The Hot Club Of San Francisco","album":"Swing This","albumart":"https://i.scdn.co/image/ab67616d00001e02f19f520e301215b718af8e2a","uri":"spotify:track:3218vQ3moEiDnVwBUapCgC","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 01 21:37:44 main volumio[824]: verbose: CURRENT POSITION 474 Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 01 21:37:44 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: info: CoreStateMachine::pushState Aug 01 21:37:44 main volumio[824]: info: CorePlayQueue::getTrack 474 Aug 01 21:37:44 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:44 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 01 21:37:50 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 01 21:37:50 main volumio[824]: info: CorePlayQueue::getTrack 474

Aug 03 21:10:48 main volumio[824]: info: Prefetching next song Aug 03 21:10:52 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","play_origin":"go-librespot"}} Aug 03 21:10:52 main volumio[824]: error: Failed to decode event: not_playing Aug 03 21:10:52 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","play_origin":"go-librespot"}}

Aug 03 21:10:52 main volumio[824]: error: Failed to decode event: not_playing Aug 03 21:10:53 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","play_origin":"go-libre spot"}} Aug 03 21:10:53 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","play_origin":"go-libre spot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","name":"La Giostra (The Merry-Go-Round)","artist_names":["Dean Martin"],"album_name":"The Essential Dean Martin","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0233 0e9e8d6fa1037f43f0c5ad","position":0,"duration":156226,"release_date":"year:2014 month:10 day:10","track_number":14,"disc_number":1}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album" :"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY ","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":n ull,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 684 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 684 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean M artin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjX YlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels ":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 684 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::play index undefined Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::startPlaybackTimer Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: [1722708654166] ControllerSpotify::clearAddPlayTrack Aug 03 21:10:54 main volumio[824]: info: Sending Spotify command with payload to local API: /player/play Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","name":"La Giostra (The Merry-Go-Round)","artist_names":["Dean Martin"],"album_name":"The Essential Dean Martin","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0233 0e9e8d6fa1037f43f0c5ad","position":0,"duration":156226,"release_date":"year:2014 month:10 day:10","track_number":14,"disc_number":1}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album" :"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY ","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":n ull,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean M artin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjX YlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels ":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: No code Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album" :"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY ","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":n ull,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean M artin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjX YlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels ":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: No code Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album" :"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY ","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":n ull,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean M artin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjX YlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels ":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: No code Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","play_origin":"go-librespo t"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album ":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqo Y","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random": null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGj XYlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channel s":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album" :"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY ","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":n ull,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean M artin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjX YlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels ":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: No code Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY","play_origin":"go-librespo t"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album ":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqo Y","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random": null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGj XYlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channel s":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:54 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean Martin","album" :"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjXYlWrgItqITMqoY ","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":n ull,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"La Giostra (The Merry-Go-Round)","artist":"Dean M artin","album":"The Essential Dean Martin","albumart":"https://i.scdn.co/image/ab67616d00001e02330e9e8d6fa1037f43f0c5ad","uri":"spotify:track:2MpjjGjX YlWrgItqITMqoY","trackType":"spotify","seek":0,"duration":156,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels ":2,"random":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:54 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: info: No code Aug 03 21:10:54 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:54 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:54 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-libre spot"}} Aug 03 21:10:54 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-libre spot"}} Aug 03 21:10:55 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","name":"Always in My Hea rt","artist_names":["Los Indios Tabajaras"],"album_name":"Always in My Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf4 8724829a51f","position":0,"duration":149960,"release_date":"year:1964 month:10 day:7","track_number":1,"disc_number":1}} Aug 03 21:10:55 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","name":"Always in My Hea rt","artist_names":["Los Indios Tabajaras"],"album_name":"Always in My Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf4 8724829a51f","position":0,"duration":149960,"release_date":"year:1964 month:10 day:7","track_number":1,"disc_number":1}} Aug 03 21:10:55 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-lib respot"}} Aug 03 21:10:55 main volumio[824]: error: Failed to decode event: not_playing Aug 03 21:10:55 main volumio[824]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-lib respot"}} Aug 03 21:10:55 main volumio[824]: error: Failed to decode event: not_playing Aug 03 21:10:55 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-libre spot"}} Aug 03 21:10:55 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-libre spot"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","name":"Always in My Hea rt","artist_names":["Los Indios Tabajaras"],"album_name":"Always in My Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf4 8724829a51f","position":0,"duration":149960,"release_date":"year:1964 month:10 day:7","track_number":1,"disc_number":1}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","name":"Always in My Hea rt","artist_names":["Los Indios Tabajaras"],"album_name":"Always in My Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf4 8724829a51f","position":0,"duration":149960,"release_date":"year:1964 month:10 day:7","track_number":1,"disc_number":1}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Al ways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTyp e":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat ":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajara s","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8 xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random ":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: info: No code Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"stop","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Al ways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTyp e":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat ":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajara s","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8 xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random ":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService stop Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: info: No code Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-libresp ot"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Al ways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTyp e":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat ":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajara s","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8 xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random ":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-libresp ot"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Al ways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTyp e":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat ":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajara s","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8 xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random ":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 03 21:10:57 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespo t"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"A lways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTy pe":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repea t":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajar as","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr 8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"rando m":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespo t"}} Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"A lways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTy pe":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repea t":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajar as","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr 8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"rando m":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"A lways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTy pe":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repea t":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajar as","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr 8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"rando m":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:10:57 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 03 21:10:57 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"A lways in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackTy pe":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repea t":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajar as","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr 8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"rando m":null,"repeat":null,"repeatSingle":null} Aug 03 21:10:57 main volumio[824]: verbose: CURRENT POSITION 685 Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 03 21:10:57 main volumio[824]: info: CoreStateMachine::pushState Aug 03 21:10:57 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:10:57 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 03 21:10:57 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 03 21:13:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 21:13:21 main volumio[824]: info: CorePlayQueue::getTrack 686 Aug 03 21:13:21 main volumio[824]: info: Prefetching next song Aug 03 22:04:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 03 22:04:09 main volumio[824]: info: Received Get System Info Aug 03 22:04:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 03 22:04:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 03 22:04:09 main volumio[824]: info: Discovery: Getting this device information Aug 03 22:04:09 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 03 22:04:09 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 22:04:09 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 03 23:06:26 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 03 23:06:26 main volumio[824]: info: Received Get System Info Aug 03 23:06:26 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 03 23:06:26 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 03 23:06:26 main volumio[824]: info: Discovery: Getting this device information Aug 03 23:06:26 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 03 23:06:26 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 03 23:06:26 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 00:08:19 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 04 00:08:19 main volumio[824]: info: Received Get System Info Aug 04 00:08:19 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 00:08:19 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 00:08:19 main volumio[824]: info: Discovery: Getting this device information Aug 04 00:08:19 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 04 00:08:19 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:08:19 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 00:45:52 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 04 00:45:52 main volumio[824]: info: CorePlayQueue::getTrack 685

But when start play this track again - its play:

Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::volumioPlay Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::play index 685 Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::stop Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::stPlaybackTimer Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::updateTrackBlock Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrackBlock Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::serviceStop Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::serviceStop Aug 04 00:55:20 main volumio[824]: info: Spotify Stop Aug 04 00:55:20 main volumio[824]: SPOTIFY: SPOTIFY STOP Aug 04 00:55:20 main volumio[824]: SPOTIFY: {"status":"play","position":685,"title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d0000b2736fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","codec":"ogg","seek":285,"duration":149,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":60,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Aug 04 00:55:20 main volumio[824]: info: Sending Spotify command to local API: /player/pause Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::play index undefined Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::startPlaybackTimer Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: info: [1722722120470] ControllerSpotify::clearAddPlayTrack Aug 04 00:55:20 main volumio[824]: info: Sending Spotify command with payload to local API: /player/play Aug 04 00:55:20 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:20 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespot"}} Aug 04 00:55:20 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 04 00:55:20 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:20 main volumio[824]: verbose: CURRENT POSITION 685 Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:20 main volumio[824]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespot"}} Aug 04 00:55:20 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 04 00:55:20 main volumio[824]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:20 main volumio[824]: verbose: CURRENT POSITION 685 Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::syncState stateService pause Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 04 00:55:20 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:20 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:20 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:20 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:20 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:20 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespot"}} Aug 04 00:55:20 main volumio[824]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespot"}} Aug 04 00:55:21 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","name":"Always in My Heart","artist_names":["Los Indios Tabajaras"],"album_name":"Always in My Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","position":0,"duration":149960,"release_date":"year:1964 month:10 day:7","track_number":1,"disc_number":1}} Aug 04 00:55:21 main volumio[824]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","name":"Always in My Heart","artist_names":["Los Indios Tabajaras"],"album_name":"Always in My Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","position":0,"duration":149960,"release_date":"year:1964 month:10 day:7","track_number":1,"disc_number":1}} Aug 04 00:55:21 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespot"}} Aug 04 00:55:21 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 04 00:55:21 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: verbose: CURRENT POSITION 685 Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState currentStatus stop Aug 04 00:55:21 main volumio[824]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","play_origin":"go-librespot"}} Aug 04 00:55:21 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 04 00:55:21 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: verbose: CURRENT POSITION 685 Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 04 00:55:21 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:21 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:21 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:21 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 04 00:55:21 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: verbose: CURRENT POSITION 685 Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 04 00:55:21 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:21 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:21 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:21 main volumio[824]: SPOTIFY: PUSH STATE SPOTIFY Aug 04 00:55:21 main volumio[824]: SPOTIFY: {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::servicePushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Always in My Heart","artist":"Los Indios Tabajaras","album":"Always in My Heart","albumart":"https://i.scdn.co/image/ab67616d00001e026fa60e4e9dbf48724829a51f","uri":"spotify:track:7uOlL4oeW3SrMugsYr8xZu","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 04 00:55:21 main volumio[824]: verbose: CURRENT POSITION 685 Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState stateService play Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::syncState currentStatus play Aug 04 00:55:21 main volumio[824]: info: Received an update from plugin. extracting info from payload Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:21 main volumio[824]: info: CoreStateMachine::pushState Aug 04 00:55:21 main volumio[824]: info: CorePlayQueue::getTrack 685 Aug 04 00:55:21 main volumio[824]: info: CoreCommandRouter::volumioPushState Aug 04 00:55:21 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:21 main volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 04 00:55:26 main volumio[824]: info: CoreCommandRouter::volumioGetState Aug 04 00:55:26 main volumio[824]: info: CorePlayQueue::getTrack 685

3rd day after updating Volumio to version 3.742. So far, not one stop of plugin. I continue monitoring. Thank you.

I was wrong.

This does not seem to be an issue with go-librespot. I am still monitoring for issues with tracks that don't play specifically.

devgianlu / go-librespot

Some tracks do not play #55