starred/go-librespot
1
0
Fork 0
mirror of https://github.com/devgianlu/go-librespot.git synced 2026-04-26 13:25:49 +03:00
Code Issues 22 Projects Releases 5 Packages Wiki Activity

[GH-ISSUE #55] Some tracks do not play #46

New issue
Closed
opened 2026-02-28 14:24:55 +03:00 by kerem · 10 comments
kerem commented 2026-02-28 14:24:55 +03:00
Owner
Copy link

Originally created by @devgianlu on GitHub (Aug 1, 2024).
Original GitHub issue: https://github.com/devgianlu/go-librespot/issues/55

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

Originally created by @devgianlu on GitHub (Aug 1, 2024). Original GitHub issue: https://github.com/devgianlu/go-librespot/issues/55 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
kerem 2026-02-28 14:24:55 +03:00
  • closed this issue
  • added the
    bug
         label
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 1, 2024):

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

<!-- gh-comment-id:2263697342 --> @sciensys commented on GitHub (Aug 1, 2024): 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](https://github.com/user-attachments/assets/4c1307a0-1a80-4f71-8ad0-52cdc04f9c41) ![web refresh - Volumio stop play 402 minutes ago](https://github.com/user-attachments/assets/fa98a80a-bc67-4b62-935d-3984039b8362)
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 1, 2024):

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

<!-- gh-comment-id:2263728691 --> @sciensys commented on GitHub (Aug 1, 2024): 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
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 3, 2024):

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"}}

<!-- gh-comment-id:2267170105 --> @sciensys commented on GitHub (Aug 3, 2024): > 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"}}
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 3, 2024):

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

<!-- gh-comment-id:2267170333 --> @sciensys commented on GitHub (Aug 3, 2024): > 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 >
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 3, 2024):

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

<!-- gh-comment-id:2267170862 --> @sciensys commented on GitHub (Aug 3, 2024): 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
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 13, 2024):

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

<!-- gh-comment-id:2285550585 --> @sciensys commented on GitHub (Aug 13, 2024): 3rd day after updating Volumio to version 3.742. So far, not one stop of plugin. I continue monitoring. Thank you.
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Aug 14, 2024):

I was wrong.

<!-- gh-comment-id:2288469157 --> @sciensys commented on GitHub (Aug 14, 2024): [I was wrong.](https://community.volumio.com/t/new-2023-spotify-plugin/63381/360?u=sciensys)
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@devgianlu commented on GitHub (Aug 14, 2024):

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.

<!-- gh-comment-id:2288477485 --> @devgianlu commented on GitHub (Aug 14, 2024): > [I was wrong.](https://community.volumio.com/t/new-2023-spotify-plugin/63381/360?u=sciensys) This does not seem to be an issue with go-librespot. I am still monitoring for issues with tracks that don't play specifically.
kerem commented 2026-02-28 14:24:56 +03:00
Author
Owner
Copy link

@devgianlu commented on GitHub (Oct 3, 2024):

Closing as there haven't been any other reports of tracks that don't play.

<!-- gh-comment-id:2392468186 --> @devgianlu commented on GitHub (Oct 3, 2024): Closing as there haven't been any other reports of tracks that don't play.
kerem commented 2026-02-28 14:24:57 +03:00
Author
Owner
Copy link

@sciensys commented on GitHub (Oct 4, 2024):

I'm sorry mate, but issue not resolved yet.
please, check post: Spotify plugin still stops play

<!-- gh-comment-id:2393951390 --> @sciensys commented on GitHub (Oct 4, 2024): I'm sorry mate, but issue not resolved yet. please, check post: [Spotify plugin still stops play](https://community.volumio.com/t/volumio-3-742-spotify-plugin-4-10-bugs/68418/28?u=sciensys)
Sign in to join this conversation.
No Branch/Tag specified
Branches Tags
master
zeroconf-avahi
windows
v0.7.1
v0.7.0
v0.6.2
v0.6.1
v0.6.0
v0.5.3
v0.5.2
v0.5.1
v0.5.0
v0.4.0
v0.3.3
v0.3.2
v0.3.1
v0.3.0
v0.2.0
v0.1.3
v0.1.2
v0.1.1
v0.1.0
v0.0.17.1
v0.0.17
v0.0.16
v0.0.15
v0.0.14
v0.0.13
v0.0.12
v0.0.11
v0.0.10
v0.0.9
v0.0.8
v0.0.7
v0.0.6
v0.0.5
v0.0.4
v0.0.3
v0.0.2
v0.0.1
Labels
Clear labels   
bug

   
enhancement

   
pull-request

Mirrored from GitHub Pull Request

  
spotify-side
No labels
bug
enhancement
pull-request
spotify-side
Milestone
Clear milestone
No items
No milestone
Projects
Clear projects
No items
No project
Assignees
Clear assignees
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference
starred/go-librespot#46
No description provided.