From april 2024 till present time, Spotify stops for 10-20 times per day

sciensys · October 26, 2024, 8:48pm

Hi,
6 month I notify the issue with Spotify, it stops playing regularly on Raspberry Pi 4B with latest Volumio OS.

today I run Raspberry Pi on ubuntu x64 with web browser Firefox.
and I see no stop when playing during all day!

but using Volumio, I see the following:

Oct 26 22:34:55 main volumio[877]: error: Failed to decode event: not_playing
Oct 26 22:38:54 main volumio[877]: error: Failed to decode event: not_playing
Oct 26 22:42:21 main volumio[877]: error: Failed to decode event: not_playing

and so on…
Is it possible to fix the error?
can we give up hope?

Oct 26 22:34:55 main volumio[877]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:4nVBt6MZDDP6tRVdQTgxJg","play_origin":"go-lib                                    respot"}}
Oct 26 22:34:55 main volumio[877]: error: Failed to decode event: not_playing
Oct 26 22:34:55 main volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","play_origin":"go-libre                                    spot"}}
Oct 26 22:34:55 main volumio[877]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","name":"Sweater Weather"                                    ,"artist_names":["The Neighbourhood"],"album_name":"I Love You.","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028265a736a1eb838ad5a0b921",                                    "position":0,"duration":240400,"release_date":"year:2013 month:4 day:22","track_number":4,"disc_number":1}}
Oct 26 22:34:55 main volumio[877]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","play_origin":"go-libresp                                    ot"}}
Oct 26 22:34:55 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:34:55 main volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"Sweater Weather","artist":"The Neighbourhood","album":"I Love Y                                    ou.","albumart":"https://i.scdn.co/image/ab67616d00001e028265a736a1eb838ad5a0b921","uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","trackType":"spotify",                                    "seek":0,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repea                                    tSingle":null}
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:34:55 main volumio[877]: info: CorePlayQueue::getTrack 6
Oct 26 22:34:55 main volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Sweater Weather","artist":"The Neighbourhood","al                                    bum":"I Love You.","albumart":"https://i.scdn.co/image/ab67616d00001e028265a736a1eb838ad5a0b921","uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","trackTy                                    pe":"spotify","seek":0,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repea                                    t":null,"repeatSingle":null}
Oct 26 22:34:55 main volumio[877]: verbose: CURRENT POSITION 6
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::syncState   stateService play
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::syncState   currentStatus play
Oct 26 22:34:55 main volumio[877]: info: Received an update from plugin. extracting info from payload
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:34:55 main volumio[877]: info: CorePlayQueue::getTrack 6
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:34:55 main volumio[877]: info: CorePlayQueue::getTrack 6
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:34:55 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:34:55 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:34:55 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:34:55 main volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"Sweater Weather","artist":"The Neighbourhood","album":"I Love Y                                    ou.","albumart":"https://i.scdn.co/image/ab67616d00001e028265a736a1eb838ad5a0b921","uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","trackType":"spotify",                                    "seek":1000,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"re                                    peatSingle":null}
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:34:55 main volumio[877]: info: CorePlayQueue::getTrack 6
Oct 26 22:34:55 main volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Sweater Weather","artist":"The Neighbourhood","al                                    bum":"I Love You.","albumart":"https://i.scdn.co/image/ab67616d00001e028265a736a1eb838ad5a0b921","uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","trackTy                                    pe":"spotify","seek":1000,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"re                                    peat":null,"repeatSingle":null}
Oct 26 22:34:55 main volumio[877]: verbose: CURRENT POSITION 6
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::syncState   stateService play
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::syncState   currentStatus play
Oct 26 22:34:55 main volumio[877]: info: Received an update from plugin. extracting info from payload
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:34:55 main volumio[877]: info: CorePlayQueue::getTrack 6
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:34:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:34:55 main volumio[877]: info: CorePlayQueue::getTrack 6
Oct 26 22:34:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:34:55 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:34:55 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:34:58 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:34:58 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:34:58 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:34:58 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:34:58 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:34:58 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:34:58 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:38:53 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:53 main volumio[877]: info: CorePlayQueue::getTrack 8
Oct 26 22:38:53 main volumio[877]: info: Prefetching next song
Oct 26 22:38:53 main volumio[877]: info: [1729971533783] ControllerSpotify::prefetch
Oct 26 22:38:53 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:38:54 main volumio[877]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2QjOHCTQ1Jl3zawyYOpxh6","play_origin":"go-lib                                    respot"}}
Oct 26 22:38:54 main volumio[877]: error: Failed to decode event: not_playing
Oct 26 22:38:54 main volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","play_origin":"go-libre                                    spot"}}
Oct 26 22:38:54 main volumio[877]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","name":"The Night We Met                                    ","artist_names":["Lord Huron"],"album_name":"Strange Trails","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0217875a0610c23d8946454583","po                                    sition":1,"duration":208211,"release_date":"year:2015 month:4 day:6","track_number":14,"disc_number":1}}
Oct 26 22:38:54 main volumio[877]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","play_origin":"go-libresp                                    ot"}}
Oct 26 22:38:54 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:38:54 main volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"The Night We Met","artist":"Lord Huron","album":"Strange Trails                                    ","albumart":"https://i.scdn.co/image/ab67616d00001e0217875a0610c23d8946454583","uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","trackType":"spotify","se                                    ek":1,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSi                                    ngle":null}
Oct 26 22:38:54 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:38:54 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:54 main volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Night We Met","artist":"Lord Huron","album":"                                    Strange Trails","albumart":"https://i.scdn.co/image/ab67616d00001e0217875a0610c23d8946454583","uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","trackType"                                    :"spotify","seek":1,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":                                    null,"repeatSingle":null}
Oct 26 22:38:54 main volumio[877]: verbose: CURRENT POSITION 7
Oct 26 22:38:54 main volumio[877]: info: CoreStateMachine::syncState   stateService play
Oct 26 22:38:54 main volumio[877]: info: CoreStateMachine::syncState   currentStatus play
Oct 26 22:38:54 main volumio[877]: info: Received an update from plugin. extracting info from payload
Oct 26 22:38:54 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:38:54 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:54 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:38:54 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:38:54 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:38:54 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:54 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:38:54 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:38:54 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:38:55 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:38:55 main volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"The Night We Met","artist":"Lord Huron","album":"Strange Trails                                    ","albumart":"https://i.scdn.co/image/ab67616d00001e0217875a0610c23d8946454583","uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","trackType":"spotify","se                                    ek":1,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSi                                    ngle":null}
Oct 26 22:38:55 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:38:55 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:55 main volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Night We Met","artist":"Lord Huron","album":"                                    Strange Trails","albumart":"https://i.scdn.co/image/ab67616d00001e0217875a0610c23d8946454583","uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","trackType"                                    :"spotify","seek":1,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":                                    null,"repeatSingle":null}
Oct 26 22:38:55 main volumio[877]: verbose: CURRENT POSITION 7
Oct 26 22:38:55 main volumio[877]: info: CoreStateMachine::syncState   stateService play
Oct 26 22:38:55 main volumio[877]: info: CoreStateMachine::syncState   currentStatus play
Oct 26 22:38:55 main volumio[877]: info: Received an update from plugin. extracting info from payload
Oct 26 22:38:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:38:55 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:55 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:38:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:38:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:38:55 main volumio[877]: info: CorePlayQueue::getTrack 7
Oct 26 22:38:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:38:55 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:38:55 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:38:58 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:38:58 main volumio[877]: info: CorePlayQueue::getTrack 8
Oct 26 22:38:58 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:38:58 main volumio[877]: info: CorePlayQueue::getTrack 8
Oct 26 22:38:58 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:38:58 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:38:58 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:3hRV0jL3vUpRrcy398teAU","play_origin":"go-lib                                    respot"}}
Oct 26 22:42:21 main volumio[877]: error: Failed to decode event: not_playing
Oct 26 22:42:21 main volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","play_origin":"go-libre                                    spot"}}
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 8
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: Prefetching next song
Oct 26 22:42:21 main volumio[877]: info: [1729971741480] ControllerSpotify::prefetch
Oct 26 22:42:21 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:42:21 main volumio[877]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","name":"LA PATRULLA","ar                                    tist_names":["Peso Pluma","Neton Vega"],"album_name":"ÉXODO","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","pos                                    ition":0,"duration":130614,"release_date":"year:2024 month:6 day:20","track_number":3,"disc_number":1}}
Oct 26 22:42:21 main volumio[877]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}}
Oct 26 22:42:21 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:42:21 main volumio[877]: SPOTIFY: {"status":"stop","service":"spop","title":"LA PATRULLA","artist":"Peso Pluma, Neton Vega","album":"ÉXODO",                                    "albumart":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","trackType":"spotify","seek                                    ":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSing                                    le":null}
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 8
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 8
Oct 26 22:42:21 main volumio[877]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"LA PATRULLA","artist":"Peso Pluma, Neton Vega","a                                    lbum":"ÉXODO","albumart":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","trackType":"                                    spotify","seek":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":nu                                    ll,"repeatSingle":null}
Oct 26 22:42:21 main volumio[877]: verbose: CURRENT POSITION 8
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::syncState   stateService stop
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::syncState   currentStatus play
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}}
Oct 26 22:42:21 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:42:21 main volumio[877]: SPOTIFY: {"status":"stop","service":"spop","title":"LA PATRULLA","artist":"Peso Pluma, Neton Vega","album":"ÉXODO",                                    "albumart":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","trackType":"spotify","seek                                    ":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSing                                    le":null}
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"LA PATRULLA","artist":"Peso Pluma, Neton Vega","a                                    lbum":"ÉXODO","albumart":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","trackType":"                                    spotify","seek":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":nu                                    ll,"repeatSingle":null}
Oct 26 22:42:21 main volumio[877]: verbose: CURRENT POSITION 9
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::syncState   stateService stop
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::syncState   currentStatus stop
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: info: No code
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:42:21 main volumio[877]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","play_origin":"go-librespo                                    t"}}
Oct 26 22:42:21 main volumio[877]: SPOTIFY: PUSH STATE SPOTIFY
Oct 26 22:42:21 main volumio[877]: SPOTIFY: {"status":"pause","service":"spop","title":"LA PATRULLA","artist":"Peso Pluma, Neton Vega","album":"ÉXODO"                                    ,"albumart":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","trackType":"spotify","see                                    k":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSin                                    gle":null}
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::servicePushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"LA PATRULLA","artist":"Peso Pluma, Neton Vega","                                    album":"ÉXODO","albumart":"https://i.scdn.co/image/ab67616d00001e022690127e1d6cd0aa35cc353b","uri":"spotify:track:2aYZaN5SmkRDLsrrV8GkBQ","trackType":                                    "spotify","seek":0,"duration":130,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":n                                    ull,"repeatSingle":null}
Oct 26 22:42:21 main volumio[877]: verbose: CURRENT POSITION 9
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::syncState   stateService pause
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::syncState   currentStatus stop
Oct 26 22:42:21 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:42:21 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:42:21 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:42:21 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:45:44 main volumio[877]: info: CorePlayQueue::getTrack 9
Oct 26 22:45:44 main volumio[877]: info: CorePlayQueue::getTrack 10
Oct 26 22:45:44 main volumio[877]: info: Prefetching next song
Oct 26 22:45:44 main volumio[877]: info: [1729971944943] ControllerSpotify::prefetch
Oct 26 22:45:44 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:45:49 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:45:49 main volumio[877]: info: CorePlayQueue::getTrack 10
Oct 26 22:45:49 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:45:49 main volumio[877]: info: CorePlayQueue::getTrack 10
Oct 26 22:45:49 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:45:49 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:45:49 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:49:28 main volumio[877]: info: CorePlayQueue::getTrack 10
Oct 26 22:49:28 main volumio[877]: info: CorePlayQueue::getTrack 11
Oct 26 22:49:28 main volumio[877]: info: Prefetching next song
Oct 26 22:49:28 main volumio[877]: info: [1729972168684] ControllerSpotify::prefetch
Oct 26 22:49:28 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:49:33 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:49:33 main volumio[877]: info: CorePlayQueue::getTrack 11
Oct 26 22:49:33 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:49:33 main volumio[877]: info: CorePlayQueue::getTrack 11
Oct 26 22:49:33 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:49:33 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:49:33 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:52:28 main volumio[877]: info: CorePlayQueue::getTrack 11
Oct 26 22:52:28 main volumio[877]: info: CorePlayQueue::getTrack 12
Oct 26 22:52:28 main volumio[877]: info: Prefetching next song
Oct 26 22:52:28 main volumio[877]: info: [1729972348371] ControllerSpotify::prefetch
Oct 26 22:52:28 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:52:32 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:52:32 main volumio[877]: info: CorePlayQueue::getTrack 12
Oct 26 22:52:33 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:52:33 main volumio[877]: info: CorePlayQueue::getTrack 12
Oct 26 22:52:33 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:52:33 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:52:33 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:55:19 main volumio[877]: info: CorePlayQueue::getTrack 12
Oct 26 22:55:19 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 26 22:55:19 main volumio[877]: info: Prefetching next song
Oct 26 22:55:19 main volumio[877]: info: [1729972519049] ControllerSpotify::prefetch
Oct 26 22:55:19 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:55:23 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:55:23 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 26 22:55:23 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:55:23 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 26 22:55:23 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:55:23 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:55:23 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 22:58:29 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 26 22:58:29 main volumio[877]: info: CorePlayQueue::getTrack 14
Oct 26 22:58:29 main volumio[877]: info: Prefetching next song
Oct 26 22:58:29 main volumio[877]: info: [1729972709747] ControllerSpotify::prefetch
Oct 26 22:58:29 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 22:58:34 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 22:58:34 main volumio[877]: info: CorePlayQueue::getTrack 14
Oct 26 22:58:34 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 22:58:34 main volumio[877]: info: CorePlayQueue::getTrack 14
Oct 26 22:58:34 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 22:58:34 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 22:58:34 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:01:18 main volumio[877]: info: CorePlayQueue::getTrack 14
Oct 26 23:01:18 main volumio[877]: info: CorePlayQueue::getTrack 15
Oct 26 23:01:18 main volumio[877]: info: Prefetching next song
Oct 26 23:01:18 main volumio[877]: info: [1729972878429] ControllerSpotify::prefetch
Oct 26 23:01:18 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:01:22 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:01:22 main volumio[877]: info: CorePlayQueue::getTrack 15
Oct 26 23:01:23 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:01:23 main volumio[877]: info: CorePlayQueue::getTrack 15
Oct 26 23:01:23 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:01:23 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:01:23 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:03:51 main volumio[877]: info: CorePlayQueue::getTrack 15
Oct 26 23:03:51 main volumio[877]: info: CorePlayQueue::getTrack 16
Oct 26 23:03:51 main volumio[877]: info: Prefetching next song
Oct 26 23:03:51 main volumio[877]: info: [1729973031093] ControllerSpotify::prefetch
Oct 26 23:03:51 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:03:55 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:03:55 main volumio[877]: info: CorePlayQueue::getTrack 16
Oct 26 23:03:55 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:03:55 main volumio[877]: info: CorePlayQueue::getTrack 16
Oct 26 23:03:55 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:03:55 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:03:56 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:06:00 main volumio[877]: info: CorePlayQueue::getTrack 16
Oct 26 23:06:00 main volumio[877]: info: CorePlayQueue::getTrack 17
Oct 26 23:06:00 main volumio[877]: info: Prefetching next song
Oct 26 23:06:00 main volumio[877]: info: [1729973160727] ControllerSpotify::prefetch
Oct 26 23:06:00 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:06:05 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:06:05 main volumio[877]: info: CorePlayQueue::getTrack 17
Oct 26 23:06:05 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:06:05 main volumio[877]: info: CorePlayQueue::getTrack 17
Oct 26 23:06:05 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:06:05 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:06:05 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:07:44 main volumio[877]: info: CorePlayQueue::getTrack 17
Oct 26 23:07:44 main volumio[877]: info: CorePlayQueue::getTrack 18
Oct 26 23:07:44 main volumio[877]: info: Prefetching next song
Oct 26 23:07:44 main volumio[877]: info: [1729973264334] ControllerSpotify::prefetch
Oct 26 23:07:44 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:07:48 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:07:48 main volumio[877]: info: CorePlayQueue::getTrack 18
Oct 26 23:07:49 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:07:49 main volumio[877]: info: CorePlayQueue::getTrack 18
Oct 26 23:07:49 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:07:49 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:07:49 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:10:07 main volumio[877]: info: CorePlayQueue::getTrack 18
Oct 26 23:10:07 main volumio[877]: info: CorePlayQueue::getTrack 19
Oct 26 23:10:07 main volumio[877]: info: Prefetching next song
Oct 26 23:10:07 main volumio[877]: info: [1729973407960] ControllerSpotify::prefetch
Oct 26 23:10:07 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:10:12 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:10:12 main volumio[877]: info: CorePlayQueue::getTrack 19
Oct 26 23:10:12 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:10:12 main volumio[877]: info: CorePlayQueue::getTrack 19
Oct 26 23:10:12 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:10:12 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:10:12 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:13:49 main volumio[877]: info: CorePlayQueue::getTrack 19
Oct 26 23:13:49 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:13:49 main volumio[877]: info: Prefetching next song
Oct 26 23:13:49 main volumio[877]: info: [1729973629661] ControllerSpotify::prefetch
Oct 26 23:13:49 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:13:54 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:13:54 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:13:54 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:13:54 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:13:54 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:13:54 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:13:54 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60
Oct 26 23:18:07 main sudo[24151]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 26 23:18:07 main sudo[24154]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 26 23:18:07 main volumio[877]: verbose: New Socket.io Connection to 192.168.70.10 from 192.168.8.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64)                                     AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 5
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:07 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 26 23:18:07 main volumio[877]: info: Received Get System Info
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 23:18:07 main volumio[877]: info: Discovery: Getting this device information
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:07 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:07 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:07 main volumio[877]: info: Listing playlists
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 26 23:18:07 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 26 23:18:08 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:08 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:09 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Oct 26 23:18:09 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 26 23:18:09 main volumio[877]: info: Received Get System Info
Oct 26 23:18:09 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 23:18:09 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 23:18:09 main volumio[877]: info: Discovery: Getting this device information
Oct 26 23:18:09 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:09 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:09 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 23:18:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 26 23:18:10 main volumio[877]: info: Received Get System Info
Oct 26 23:18:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 23:18:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 23:18:10 main volumio[877]: info: Discovery: Getting this device information
Oct 26 23:18:10 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:10 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 23:18:17 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 26 23:18:25 main volumio[877]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Oct 26 23:18:25 main volumio[877]: info: Received Get System Version
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 26 23:18:25 main volumio[877]: info: Received Get System Info
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 23:18:25 main volumio[877]: info: Discovery: Getting this device information
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 26 23:18:25 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:25 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 23:18:36 main volumio[877]: info: CorePlayQueue::getTrack 20
Oct 26 23:18:36 main volumio[877]: info: CorePlayQueue::getTrack 21
Oct 26 23:18:36 main volumio[877]: info: Prefetching next song
Oct 26 23:18:36 main volumio[877]: info: [1729973916195] ControllerSpotify::prefetch
Oct 26 23:18:36 main volumio[877]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Oct 26 23:18:40 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 26 23:18:40 main volumio[877]: info: CorePlayQueue::getTrack 21
Oct 26 23:18:41 main volumio[877]: info: CoreStateMachine::pushState
Oct 26 23:18:41 main volumio[877]: info: CorePlayQueue::getTrack 21
Oct 26 23:18:41 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 26 23:18:41 main volumio[877]: info: CoreCommandRouter::volumioPushState
Oct 26 23:18:41 main volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 6

sciensys · October 27, 2024, 5:43pm

Today is a real holiday…
Spotify stops every half hour…
it’s funny.


Oct 27 20:35:38 main volumio[877]: info: CoreCommandRouter::volumioPlay
Oct 27 20:35:38 main volumio[877]: info: CoreStateMachine::play index undefined
Oct 27 20:35:38 main volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 27 20:35:38 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 27 20:35:38 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 27 20:35:38 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 27 20:35:38 main volumio[877]: info: [1730050538350] ControllerSpotify::clearAddPlayTrack
Oct 27 20:35:38 main volumio[877]: info: Sending Spotify command with payload to local API: /player/play
Oct 27 20:35:38 main go-librespot[1094]: time="2024-10-27T20:35:38+03:00" level=debug msg="resolved context of track" uri="spotify:track:4odjKrQcVe12RiUom35uvY"
Oct 27 20:35:38 main go-librespot[1094]: time="2024-10-27T20:35:38+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4odjKrQcVe12RiUom35uvY"
Oct 27 20:35:38 main go-librespot[1094]: time="2024-10-27T20:35:38+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4odjKrQcVe12RiUom35uvY"
Oct 27 20:35:38 main go-librespot[1094]: time="2024-10-27T20:35:38+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Oct 27 20:35:38 main go-librespot[1094]: time="2024-10-27T20:35:38+03:00" level=trace msg="emitting websocket event: will_play"
Oct 27 20:35:38 main volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4odjKrQcVe12RiUom35uvY","play_origin":"go-librespot"}}
Oct 27 20:35:38 main go-librespot[1094]: time="2024-10-27T20:35:38+03:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (lo
Oct 27 20:35:38 main volumio[877]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error
Oct 27 20:36:35 main volumio[877]: info: CoreCommandRouter::volumioPlay
Oct 27 20:36:35 main volumio[877]: info: CoreStateMachine::play index undefined
Oct 27 20:36:35 main volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 27 20:36:35 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 27 20:36:35 main volumio[877]: info: CoreStateMachine::startPlaybackTimer
Oct 27 20:36:35 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 27 20:36:35 main volumio[877]: info: [1730050595276] ControllerSpotify::clearAddPlayTrack
Oct 27 20:36:35 main volumio[877]: info: Sending Spotify command with payload to local API: /player/play
Oct 27 20:36:35 main go-librespot[1094]: time="2024-10-27T20:36:35+03:00" level=debug msg="resolved context of track" uri="spotify:track:4odjKrQcVe12RiUom35uvY"
Oct 27 20:36:35 main go-librespot[1094]: time="2024-10-27T20:36:35+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4odjKrQcVe12RiUom35uvY"
Oct 27 20:36:35 main go-librespot[1094]: time="2024-10-27T20:36:35+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4odjKrQcVe12RiUom35uvY"
Oct 27 20:36:35 main go-librespot[1094]: time="2024-10-27T20:36:35+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Oct 27 20:36:35 main go-librespot[1094]: time="2024-10-27T20:36:35+03:00" level=trace msg="emitting websocket event: will_play"
Oct 27 20:36:35 main volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4odjKrQcVe12RiUom35uvY","play_origin":"go-librespot"}}
Oct 27 20:36:35 main go-librespot[1094]: time="2024-10-27T20:36:35+03:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (lo
Oct 27 20:36:35 main volumio[877]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error
Oct 27 20:37:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 27 20:37:10 main volumio[877]: info: Received Get System Info
Oct 27 20:37:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 27 20:37:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 27 20:37:10 main volumio[877]: info: Discovery: Getting this device information
Oct 27 20:37:10 main volumio[877]: info: CoreCommandRouter::volumioGetState
Oct 27 20:37:10 main volumio[877]: info: CorePlayQueue::getTrack 13
Oct 27 20:37:10 main volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses

sciensys · October 28, 2024, 8:19am

In general, the hint for six months is clear.
It’s a pity, Volumio is not a bad music operating system.
But I don’t see the point in buying subscriptions, especially any hardware solutions.

P.S. I am currently actively testing the browser version of Spotify on Raspberry Pi 4B, and for two days there hasn’t been a single playback stop - amazing!