-- Logs begin at Tue 2025-12-09 21:07:24 CET, end at Tue 2025-12-09 21:21:38 CET. -- Dec 09 21:20:00 volumio ntpd[3188]: Soliciting pool server 82.64.42.185 Dec 09 21:20:00 volumio ntpd[3188]: Soliciting pool server 82.64.42.185 Dec 09 21:20:00 volumio wireless.js[2924]: trying... Dec 09 21:20:00 volumio ntpd[3188]: Soliciting pool server 172.233.248.179 Dec 09 21:20:00 volumio sudo[3263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:00 volumio sudo[3263]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:00 volumio sudo[3263]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:01 volumio ntpd[3188]: Soliciting pool server 172.232.63.219 Dec 09 21:20:01 volumio ntpd[3188]: Soliciting pool server 51.68.44.27 Dec 09 21:20:01 volumio wireless.js[2924]: trying... Dec 09 21:20:01 volumio sudo[3274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:01 volumio sudo[3274]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:01 volumio sudo[3274]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:02 volumio ntpd[3188]: Soliciting pool server 5.42.158.119 Dec 09 21:20:02 volumio ntpd[3188]: Soliciting pool server 45.140.164.53 Dec 09 21:20:02 volumio wireless.js[2924]: trying... Dec 09 21:20:02 volumio sudo[3284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:02 volumio sudo[3284]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:02 volumio sudo[3284]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:02 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:02 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:02 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:02 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Dec 09 21:20:02 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:02 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Dec 09 21:20:02 volumio volumio[1234]: info: [1765311602365] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:02 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:02 volumio go-librespot[1638]: time="2025-12-09T21:20:02+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:02 volumio go-librespot[1638]: time="2025-12-09T21:20:02+01:00" level=debug msg="loading track spotify:track:4DDocT20ZbnfCqJjgLwczK (paused: false, position: 1ms)" Dec 09 21:20:02 volumio go-librespot[1638]: time="2025-12-09T21:20:02+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:02 volumio go-librespot[1638]: time="2025-12-09T21:20:02+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:02 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4DDocT20ZbnfCqJjgLwczK","play_origin":"go-librespot"}} Dec 09 21:20:02 volumio go-librespot[1638]: time="2025-12-09T21:20:02+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:4DDocT20ZbnfCqJjgLwczK" Dec 09 21:20:02 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:03 volumio ntpd[3188]: Soliciting pool server 38.143.19.16 Dec 09 21:20:03 volumio ntpd[3188]: Soliciting pool server 2a12:bec4:1821:25c::123 Dec 09 21:20:03 volumio wireless.js[2924]: trying... Dec 09 21:20:03 volumio sudo[3294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:03 volumio sudo[3294]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:03 volumio sudo[3294]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:20:03 volumio volumio[1234]: info: In handleBrowseUri, curUri=spotify Dec 09 21:20:03 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:03 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:03 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:03 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:04 volumio wireless.js[2924]: trying... Dec 09 21:20:04 volumio sudo[3304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:04 volumio sudo[3304]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:04 volumio sudo[3304]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:05 volumio wireless.js[2924]: trying... Dec 09 21:20:05 volumio sudo[3314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:05 volumio sudo[3314]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:20:05 volumio volumio[1234]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DWVuV87wUBNwc Dec 09 21:20:05 volumio sudo[3314]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:05 volumio go-librespot[1638]: time="2025-12-09T21:20:05+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWVuV87wUBNwc" Dec 09 21:20:05 volumio go-librespot[1638]: time="2025-12-09T21:20:05+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 09 21:20:06 volumio wireless.js[2924]: trying... Dec 09 21:20:06 volumio sudo[3324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:06 volumio sudo[3324]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:06 volumio sudo[3324]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:06 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:06 volumio volumio[1234]: info: Preloading song: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:1xOqGUkyxGQRdCvGpvWKmL in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:53iuhJlwXhSER5J2IYYv1W in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:3Dy4xsff7c0fRaTJpA54yd in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:6eDKyG6jvCXaTyTdFOh9wA in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:53iuhJlwXhSER5J2IYYv1W","service":"spop","name":"The Fate of Ophelia","artist":"Taylor Swift","album":"The Life of a Showgirl","type":"song","duration":226,"albumart":"https://i.scdn.co/image/ab67616d0000b273d7812467811a7da6e6a44902","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:3NFs3XUduzBfvc5Bx1gmzh in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Dy4xsff7c0fRaTJpA54yd","service":"spop","name":"Gone Gone Gone","artist":"David Guetta","album":"Gone Gone Gone","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b273dff5897733ec36cdb24db9a2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6eDKyG6jvCXaTyTdFOh9wA","service":"spop","name":"QUE CE SOIT CLAIR","artist":"Paul Kalkbrenner","album":"QUE CE SOIT CLAIR","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273a9e808e8b3dee391af2217ad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:7lf6EvTJtrbLKtJ1BKP3hv in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1xOqGUkyxGQRdCvGpvWKmL","service":"spop","name":"Gabriela","artist":"KATSEYE","album":"Gabriela","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273f8d4d00ffe09373efb13ce29","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3NFs3XUduzBfvc5Bx1gmzh","service":"spop","name":"The Dead Dance","artist":"Lady Gaga","album":"The Dead Dance","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8a389abdf9cb2d03b85ff54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:6wHpLMmp98aHcV8L1JFrj8 in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:1CPZ5BxNNd0n0nF4Orb9JS in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:2lwLLpCLIQ6lc5EvvdjG5C in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","service":"spop","name":"Eternity","artist":"Alex Warren","album":"You'll Be Alright, Kid","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0a2d7a2d8406a4a72a92d5c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7lf6EvTJtrbLKtJ1BKP3hv","service":"spop","name":"SENTIMENTAL","artist":"GIMS","album":"SENTIMENTAL","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273e884d337e3b5ebd3c7ecfe94","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:2t4RCWbICUqhPKVQeA7VPq in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","service":"spop","name":"Golden","artist":"HUNTR/X","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2734dcb6c5df15cf74596ab25a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:06 volumio volumio[1234]: info: Exploding uri spotify:track:2LVLfi56877BcKEPkrUuYZ in service spop Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:06 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2lwLLpCLIQ6lc5EvvdjG5C","service":"spop","name":"Ailleurs","artist":"Orelsan","album":"La fuite en avant","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b2731231ef8dd71240516508dbd7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:10WWosq6z75o3WGRjL4v2D in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:3yWuTOYDztXjZxdE2cIRUa in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2t4RCWbICUqhPKVQeA7VPq","service":"spop","name":"The First Time","artist":"Damiano David","album":"FUNNY little FEARS","type":"song","duration":218,"albumart":"https://i.scdn.co/image/ab67616d0000b27328b40925cffb9c193661272a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LVLfi56877BcKEPkrUuYZ","service":"spop","name":"Tout a changé (Rien n'a changé)","artist":"Helena","album":"Hélé","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b2739dff5b5fc6eb9fac50278c12","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:7CdgI4KOU4WnrBHv91Vpm6 in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:10WWosq6z75o3WGRjL4v2D","service":"spop","name":"Paradise (with Bipolar Sunshine)","artist":"DJ Snake","album":"Paradise (with Bipolar Sunshine)","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c78b099b9129b2d48146653","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:7xfOV2VUEd39JXePJ1j9Os in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3yWuTOYDztXjZxdE2cIRUa","service":"spop","name":"Opalite","artist":"Taylor Swift","album":"The Life of a Showgirl","type":"song","duration":235,"albumart":"https://i.scdn.co/image/ab67616d0000b273d7812467811a7da6e6a44902","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:2oaGtVSJwivQkslaiDXOTr in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7CdgI4KOU4WnrBHv91Vpm6","service":"spop","name":"L'horizon","artist":"Pierre Garnier","album":"Chaque seconde","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b27349b57472dfa8359df0f0d8c2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7xfOV2VUEd39JXePJ1j9Os","service":"spop","name":"Sarà perchè ti amo","artist":"DJ Antoine","album":"Sarà perchè ti amo","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273ec01436a049f7f12699a6fad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:3SMtVY7EyN0tSYhAZl1AA7 in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:07 volumio wireless.js[2924]: trying... Dec 09 21:20:07 volumio sudo[3334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:20:07 volumio sudo[3336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:1UNEuG9DYOWiikf00ayr52 in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:07 volumio sudo[3334]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:07 volumio sudo[3336]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:07 volumio sudo[3340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:07 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:07 volumio sudo[3336]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:07 volumio sudo[3340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:07 volumio sudo[3340]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:7xWvRVssTTECvWCDNgCzSH in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:07 volumio sudo[3362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 09 21:20:07 volumio sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SMtVY7EyN0tSYhAZl1AA7","service":"spop","name":"Autrement","artist":"Julien Lieb","album":"Autrement","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b27323345d5a8d94dadaab9100d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio sudo[3362]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:3XJQz97R6A7bXn8awvFknM in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:07 volumio volumio[1234]: info: Upmpdcli Daemon Started Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2oaGtVSJwivQkslaiDXOTr","service":"spop","name":"Hips Don't Lie - Spotify Anniversary Version","artist":"Shakira","album":"Spotify Anniversary | Oral Fixation (20th) and Pies Descalzos (30th) LIVE","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273d5c717a748b40b026290622f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7xWvRVssTTECvWCDNgCzSH","service":"spop","name":"Soleil Bleu","artist":"Bleu Soleil","album":"Soleil Bleu","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b2735446164bb6862bb37594b5f6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:6qB2O09Bxs3bCKiWT4UVOv in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:07 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:07 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:07 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Dec 09 21:20:07 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:1UNEuG9DYOWiikf00ayr52 in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:3XJQz97R6A7bXn8awvFknM in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:6qB2O09Bxs3bCKiWT4UVOv in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:4GOYVxQPaKqen2QcoLtrlc in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:1VeDrlbLw18quxCqWfEFEi in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:3sK8wGT43QFpWrvNQsrQya in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:02sy7FAs8dkDNYsHp4Ul3f in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:0ZaqWNMYcXW8ycRfVkgawy in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:6MrLkXsMmHaYt680fhJUAq in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:4Q0qVhFQa7j6jRKzo3HDmP in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:07 volumio volumio[1234]: info: Using cached record of: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:6qqrTXSdwiJaq8SO0X2lSe in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:1qbmS6ep2hbBRaEZFpn7BX in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:6LChsTTDPShYFbzngKMSWK in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:5H1sKFMzDeMtXwND3V6hRY in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:62eKaBn3ahoFQHIyqaAZk7 in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:2SXIxc92q42M6lMCfQ8hKL in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:5vXPLxljVKyHDHuv3kIuKV in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:0NRIWkmQiSoIaEs3wvoSS7 in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:6bcDH1OOwuKzfsByqsQKhH in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:07 volumio volumio[1234]: info: Adding Item to queue: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:07 volumio volumio[1234]: info: Exploding uri spotify:track:06g67XFL3EvhddvSG5Cuku in service spop Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: EXPLODING URI:spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3XJQz97R6A7bXn8awvFknM","service":"spop","name":"Together","artist":"David Guetta","album":"Together","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b273e71b75de8bb92176df63f476","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5H1sKFMzDeMtXwND3V6hRY","service":"spop","name":"JUMP","artist":"BLACKPINK","album":"JUMP","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b2736224d1236b0e0a0e1586efbb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4GOYVxQPaKqen2QcoLtrlc","service":"spop","name":"Stay (If You Wanna Dance)","artist":"Myles Smith","album":"Stay (If You Wanna Dance)","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b27374fe60cdbd694ff369ceba4a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1UNEuG9DYOWiikf00ayr52","service":"spop","name":"Love Me Not","artist":"Ravyn Lenae","album":"Love Me Not / Love Is Blind","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2735511ceaa955a2bfd9ae90c21","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ZaqWNMYcXW8ycRfVkgawy","service":"spop","name":"Viens on essaie","artist":"Vitaa","album":"Viens on essaie","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b273a294c19a0fbeeb8ee9515ef6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qB2O09Bxs3bCKiWT4UVOv","service":"spop","name":"Game Started","artist":"Sound Of Legend","album":"Game Started","type":"song","duration":148,"albumart":"https://i.scdn.co/image/ab67616d0000b27365cd1f4251b725c1159863d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Q0qVhFQa7j6jRKzo3HDmP","service":"spop","name":"Sapphire","artist":"Ed Sheeran","album":"Sapphire","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fbb60d6a7e03ccb940a518e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3XJQz97R6A7bXn8awvFknM","service":"spop","name":"Together","artist":"David Guetta","album":"Together","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b273e71b75de8bb92176df63f476","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1UNEuG9DYOWiikf00ayr52","service":"spop","name":"Love Me Not","artist":"Ravyn Lenae","album":"Love Me Not / Love Is Blind","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2735511ceaa955a2bfd9ae90c21","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6bcDH1OOwuKzfsByqsQKhH","service":"spop","name":"AIR FORCE BLANCHE","artist":"GIMS","album":"AIR FORCE BLANCHE","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b27343f05c68035b2ada52d2e1a9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qB2O09Bxs3bCKiWT4UVOv","service":"spop","name":"Game Started","artist":"Sound Of Legend","album":"Game Started","type":"song","duration":148,"albumart":"https://i.scdn.co/image/ab67616d0000b27365cd1f4251b725c1159863d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6LChsTTDPShYFbzngKMSWK","service":"spop","name":"TU ME RENDS BÊTE","artist":"GIMS","album":"TU ME RENDS BÊTE","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b27355842bfbfe259ebedac5f193","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3sK8wGT43QFpWrvNQsrQya","service":"spop","name":"DtMF","artist":"Bad Bunny","album":"DeBÍ TiRAR MáS FOToS","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273bbd45c8d36e0e045ef640411","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1VeDrlbLw18quxCqWfEFEi","service":"spop","name":"Tant pis pour elle","artist":"Charlotte Cardin","album":"Tant pis pour elle","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b273e737dc0e5ab8663c1e81dac0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:06g67XFL3EvhddvSG5Cuku","service":"spop","name":"Hit My Heart - TR3NACRIA Remix","artist":"Benassi Bros.","album":"Hit My Heart (TR3NACRIA Remix)","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b2732d97910987bef5f8ad4ac64c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6MrLkXsMmHaYt680fhJUAq","service":"spop","name":"12 to 12","artist":"sombr","album":"12 to 12","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273e261428c386eb6de93bae544","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:02sy7FAs8dkDNYsHp4Ul3f","service":"spop","name":"Soda Pop","artist":"Saja Boys","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b2734dcb6c5df15cf74596ab25a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qqrTXSdwiJaq8SO0X2lSe","service":"spop","name":"Ordinary","artist":"Alex Warren","album":"Ordinary","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273fa2bcf3df669bde08b2e7d36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:62eKaBn3ahoFQHIyqaAZk7","service":"spop","name":"Orchestra - 20th Anniversary Version","artist":"Dan Black","album":"Orchestra (20th Anniversary Version)","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b27358eee9e682a5cdc4cf16769e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qbmS6ep2hbBRaEZFpn7BX","service":"spop","name":"Man I Need","artist":"Olivia Dean","album":"Man I Need","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273e3d69e17dde129037a1f09e2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vXPLxljVKyHDHuv3kIuKV","service":"spop","name":"Survive","artist":"Lewis Capaldi","album":"Survive","type":"song","duration":225,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6c69a4c4a69e0cdd5105f8e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2SXIxc92q42M6lMCfQ8hKL","service":"spop","name":"Give It To Me 2025","artist":"Adriatique","album":"Give It To Me 2025","type":"song","duration":161,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff2239cdc16033cf040ed2b0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0NRIWkmQiSoIaEs3wvoSS7","service":"spop","name":"A New Day (feat. Celine Dion)","artist":"Sebastian Ingrosso","album":"A New Day (feat. Celine Dion)","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b2733a795c78b938d24a25d9b4a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:20:07 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:07 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::play index 5 Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::getTrack 5 Dec 09 21:20:07 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:07 volumio volumio[1234]: info: CorePlayQueue::getTrack 5 Dec 09 21:20:07 volumio volumio[1234]: info: [1765311607832] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:07 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:07 volumio go-librespot[1638]: time="2025-12-09T21:20:07+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:07 volumio go-librespot[1638]: time="2025-12-09T21:20:07+01:00" level=debug msg="loading track spotify:track:6eDKyG6jvCXaTyTdFOh9wA (paused: false, position: 0ms)" Dec 09 21:20:07 volumio go-librespot[1638]: time="2025-12-09T21:20:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:07 volumio go-librespot[1638]: time="2025-12-09T21:20:07+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:07 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6eDKyG6jvCXaTyTdFOh9wA","play_origin":"go-librespot"}} Dec 09 21:20:07 volumio go-librespot[1638]: time="2025-12-09T21:20:07+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6eDKyG6jvCXaTyTdFOh9wA" Dec 09 21:20:07 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:08 volumio wireless.js[2924]: trying... Dec 09 21:20:08 volumio sudo[3374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:08 volumio sudo[3374]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:08 volumio sudo[3374]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:08 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:08 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:08 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:08 volumio volumio[1234]: info: CorePlayQueue::getTrack 5 Dec 09 21:20:08 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:08 volumio volumio[1234]: info: CorePlayQueue::getTrack 5 Dec 09 21:20:08 volumio volumio[1234]: info: [1765311608869] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:08 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:08 volumio go-librespot[1638]: time="2025-12-09T21:20:08+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:08 volumio go-librespot[1638]: time="2025-12-09T21:20:08+01:00" level=debug msg="loading track spotify:track:6eDKyG6jvCXaTyTdFOh9wA (paused: false, position: 0ms)" Dec 09 21:20:08 volumio go-librespot[1638]: time="2025-12-09T21:20:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:08 volumio go-librespot[1638]: time="2025-12-09T21:20:08+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:08 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6eDKyG6jvCXaTyTdFOh9wA","play_origin":"go-librespot"}} Dec 09 21:20:08 volumio go-librespot[1638]: time="2025-12-09T21:20:08+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6eDKyG6jvCXaTyTdFOh9wA" Dec 09 21:20:08 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:09 volumio wireless.js[2924]: trying... Dec 09 21:20:09 volumio sudo[3384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 09 21:20:09 volumio sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:09 volumio sudo[3384]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:10 volumio wireless.js[2924]: Overtime, starting plan B Dec 09 21:20:10 volumio wireless.js[2924]: STARTING HOTSPOT Dec 09 21:20:10 volumio wpa_supplicant[3044]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Dec 09 21:20:10 volumio wpa_supplicant[3044]: nl80211: Failed to set IPv4 unicast in multicast filter Dec 09 21:20:10 volumio wpa_supplicant[3044]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Dec 09 21:20:10 volumio wpa_supplicant[3044]: nl80211: Failed to set IPv4 unicast in multicast filter Dec 09 21:20:10 volumio wpa_supplicant[3044]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Dec 09 21:20:10 volumio dhcpcd[593]: wlan0: carrier acquired Dec 09 21:20:10 volumio wpa_supplicant[3044]: wlan0: CTRL-EVENT-TERMINATING Dec 09 21:20:10 volumio dhcpcd[593]: wlan0: IAID 55:76:56:63 Dec 09 21:20:10 volumio dhcpcd[593]: wlan0: carrier lost Dec 09 21:20:11 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:11 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:11 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:11 volumio volumio[1234]: info: CorePlayQueue::getTrack 5 Dec 09 21:20:11 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:11 volumio volumio[1234]: info: CorePlayQueue::getTrack 5 Dec 09 21:20:11 volumio volumio[1234]: info: [1765311611301] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:11 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:11 volumio go-librespot[1638]: time="2025-12-09T21:20:11+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:11 volumio go-librespot[1638]: time="2025-12-09T21:20:11+01:00" level=debug msg="loading track spotify:track:6eDKyG6jvCXaTyTdFOh9wA (paused: false, position: 0ms)" Dec 09 21:20:11 volumio go-librespot[1638]: time="2025-12-09T21:20:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:11 volumio go-librespot[1638]: time="2025-12-09T21:20:11+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:11 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6eDKyG6jvCXaTyTdFOh9wA","play_origin":"go-librespot"}} Dec 09 21:20:11 volumio go-librespot[1638]: time="2025-12-09T21:20:11+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6eDKyG6jvCXaTyTdFOh9wA" Dec 09 21:20:11 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:12 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:12 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:12 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Dec 09 21:20:12 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:12 volumio volumio[1234]: info: Adding Item to queue: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:12 volumio volumio[1234]: info: Using cached record of: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:12 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:12 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::play index 11 Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::getTrack 11 Dec 09 21:20:12 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:12 volumio volumio[1234]: info: CorePlayQueue::getTrack 11 Dec 09 21:20:12 volumio volumio[1234]: info: [1765311612590] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:12 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:12 volumio go-librespot[1638]: time="2025-12-09T21:20:12+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:12 volumio go-librespot[1638]: time="2025-12-09T21:20:12+01:00" level=debug msg="loading track spotify:track:2lwLLpCLIQ6lc5EvvdjG5C (paused: false, position: 0ms)" Dec 09 21:20:12 volumio go-librespot[1638]: time="2025-12-09T21:20:12+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:12 volumio go-librespot[1638]: time="2025-12-09T21:20:12+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:12 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2lwLLpCLIQ6lc5EvvdjG5C","play_origin":"go-librespot"}} Dec 09 21:20:12 volumio go-librespot[1638]: time="2025-12-09T21:20:12+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:2lwLLpCLIQ6lc5EvvdjG5C" Dec 09 21:20:12 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:13 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Dec 09 21:20:13 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::play index 11 Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::getTrack 11 Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::getTrack 11 Dec 09 21:20:13 volumio volumio[1234]: info: [1765311613377] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:13 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=debug msg="loading track spotify:track:2lwLLpCLIQ6lc5EvvdjG5C (paused: false, position: 0ms)" Dec 09 21:20:13 volumio sudo[3415]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 09 21:20:13 volumio sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:13 volumio sudo[3415]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:13 volumio sudo[3424]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:13 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2lwLLpCLIQ6lc5EvvdjG5C","play_origin":"go-librespot"}} Dec 09 21:20:13 volumio sudo[3424]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:13 volumio sudo[3424]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:13 volumio wireless.js[2924]: Hotspot is disabled, not starting it Dec 09 21:20:13 volumio dhcpcd[593]: wlan0: carrier acquired Dec 09 21:20:13 volumio dhcpcd[593]: wlan0: IAID 55:76:56:63 Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:2lwLLpCLIQ6lc5EvvdjG5C" Dec 09 21:20:13 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:13 volumio iwconfig[3437]: Error for wireless request "Set Power Management" (8B2C) : Dec 09 21:20:13 volumio iwconfig[3437]: SET failed on device wlan0 ; Operation not permitted. Dec 09 21:20:13 volumio systemd[1]: wireless.service: Succeeded. Dec 09 21:20:13 volumio systemd[1]: Started Wireless Services. Dec 09 21:20:13 volumio sudo[2841]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:13 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:13 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Dec 09 21:20:13 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:13 volumio volumio[1234]: info: Adding Item to queue: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:13 volumio volumio[1234]: info: Using cached record of: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:13 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::play index 11 Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::getTrack 11 Dec 09 21:20:13 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:13 volumio volumio[1234]: info: CorePlayQueue::getTrack 11 Dec 09 21:20:13 volumio volumio[1234]: info: [1765311613558] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:13 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=debug msg="loading track spotify:track:2lwLLpCLIQ6lc5EvvdjG5C (paused: false, position: 1ms)" Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:13 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2lwLLpCLIQ6lc5EvvdjG5C","play_origin":"go-librespot"}} Dec 09 21:20:13 volumio go-librespot[1638]: time="2025-12-09T21:20:13+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:2lwLLpCLIQ6lc5EvvdjG5C" Dec 09 21:20:13 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:13 volumio dhcpcd[593]: wlan0: soliciting a DHCP lease Dec 09 21:20:14 volumio dhcpcd[593]: wlan0: soliciting an IPv6 router Dec 09 21:20:18 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 09 21:20:18 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 09 21:20:18 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 09 21:20:18 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 09 21:20:18 volumio sudo[3445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:18 volumio sudo[3447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:18 volumio sudo[3445]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:18 volumio sudo[3447]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:18 volumio sudo[3445]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:18 volumio sudo[3447]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:18 volumio dhcpcd[593]: wlan0: probing for an IPv4LL address Dec 09 21:20:20 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:20 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:20 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Dec 09 21:20:20 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:20 volumio volumio[1234]: info: Adding Item to queue: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:20 volumio volumio[1234]: info: Using cached record of: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:20 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:20 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::play index 10 Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::getTrack 10 Dec 09 21:20:20 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:20 volumio volumio[1234]: info: CorePlayQueue::getTrack 10 Dec 09 21:20:20 volumio volumio[1234]: info: [1765311620490] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:20 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:20 volumio go-librespot[1638]: time="2025-12-09T21:20:20+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:20 volumio go-librespot[1638]: time="2025-12-09T21:20:20+01:00" level=debug msg="loading track spotify:track:1CPZ5BxNNd0n0nF4Orb9JS (paused: false, position: 0ms)" Dec 09 21:20:20 volumio go-librespot[1638]: time="2025-12-09T21:20:20+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:20 volumio go-librespot[1638]: time="2025-12-09T21:20:20+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:20 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","play_origin":"go-librespot"}} Dec 09 21:20:20 volumio go-librespot[1638]: time="2025-12-09T21:20:20+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Dec 09 21:20:20 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 09 21:20:23 volumio volumio[1234]: info: Refreshing Cached IP Addresses Dec 09 21:20:23 volumio sudo[3466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 09 21:20:23 volumio sudo[3466]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:23 volumio sudo[3469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 09 21:20:23 volumio sudo[3466]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:23 volumio sudo[3469]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:23 volumio sudo[3474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 09 21:20:23 volumio volumio[1234]: info: Refreshing Cached IP Addresses Dec 09 21:20:23 volumio sudo[3469]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:23 volumio sudo[3474]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:23 volumio sudo[3474]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:23 volumio sudo[3485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 09 21:20:23 volumio sudo[3485]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:23 volumio sudo[3499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:20:23 volumio volumio[1234]: error: Upnp client error: Error: This socket has been ended by the other party Dec 09 21:20:23 volumio volumio[1234]: error: Upnp client error: Error: This socket has been ended by the other party Dec 09 21:20:23 volumio sudo[3485]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:23 volumio sudo[3499]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:23 volumio sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:23 volumio sudo[3499]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:23 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:23 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:23 volumio dhcpcd[593]: wlan0: using IPv4LL address 169.254.69.48 Dec 09 21:20:23 volumio dhcpcd[593]: wlan0: adding route to 169.254.0.0/16 Dec 09 21:20:23 volumio avahi-daemon[514]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.69.48. Dec 09 21:20:23 volumio avahi-daemon[514]: New relevant interface wlan0.IPv4 for mDNS. Dec 09 21:20:23 volumio avahi-daemon[514]: Registering new address record for 169.254.69.48 on wlan0.IPv4. Dec 09 21:20:23 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Dec 09 21:20:23 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:5Y8C6KjzBRKvcT3Aln1Bc4 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:53iuhJlwXhSER5J2IYYv1W Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1REP1cQ1YarUEvQ5krpO53 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3Dy4xsff7c0fRaTJpA54yd Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6eDKyG6jvCXaTyTdFOh9wA Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:7lf6EvTJtrbLKtJ1BKP3hv Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3JzeDkdvorMWUvaAnU7BCX Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:2lwLLpCLIQ6lc5EvvdjG5C Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:2t4RCWbICUqhPKVQeA7VPq Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:2LVLfi56877BcKEPkrUuYZ Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:10WWosq6z75o3WGRjL4v2D Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3yWuTOYDztXjZxdE2cIRUa Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:7CdgI4KOU4WnrBHv91Vpm6 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:7xfOV2VUEd39JXePJ1j9Os Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:2oaGtVSJwivQkslaiDXOTr Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3SMtVY7EyN0tSYhAZl1AA7 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3igbfkHq3s5wGMvC8iAscj Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1UNEuG9DYOWiikf00ayr52 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:7xWvRVssTTECvWCDNgCzSH Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3XJQz97R6A7bXn8awvFknM Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3tIib0N5bPFPlt9DmUwLvh Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6qB2O09Bxs3bCKiWT4UVOv Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1yGJ40k7HLF3odITZPoQs4 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:4kzHds1mgNB0kuCLHeOGiV Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:4GOYVxQPaKqen2QcoLtrlc Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1VeDrlbLw18quxCqWfEFEi Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:3sK8wGT43QFpWrvNQsrQya Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:0DAhk47iSMkfPKm5MDW17x Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:02sy7FAs8dkDNYsHp4Ul3f Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:0ZaqWNMYcXW8ycRfVkgawy Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6MrLkXsMmHaYt680fhJUAq Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:7DnucB1OzPCM8qP7dziWbC Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:5DTahoMKuix8Fi1WSXGwIW Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6LChsTTDPShYFbzngKMSWK Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:62eKaBn3ahoFQHIyqaAZk7 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:2SXIxc92q42M6lMCfQ8hKL Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:5vXPLxljVKyHDHuv3kIuKV Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:0NRIWkmQiSoIaEs3wvoSS7 Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:6bcDH1OOwuKzfsByqsQKhH Dec 09 21:20:23 volumio volumio[1234]: info: Adding Item to queue: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:23 volumio volumio[1234]: info: Using cached record of: spotify:track:06g67XFL3EvhddvSG5Cuku Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::saveQueue Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:23 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::play index 7 Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:23 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:23 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:23 volumio volumio[1234]: info: [1765311623984] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:23 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:24 volumio go-librespot[1638]: time="2025-12-09T21:20:24+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:24 volumio go-librespot[1638]: time="2025-12-09T21:20:24+01:00" level=debug msg="loading track spotify:track:7lf6EvTJtrbLKtJ1BKP3hv (paused: false, position: 1ms)" Dec 09 21:20:24 volumio go-librespot[1638]: time="2025-12-09T21:20:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:24 volumio go-librespot[1638]: time="2025-12-09T21:20:24+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:24 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7lf6EvTJtrbLKtJ1BKP3hv","play_origin":"go-librespot"}} Dec 09 21:20:24 volumio go-librespot[1638]: time="2025-12-09T21:20:24+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:7lf6EvTJtrbLKtJ1BKP3hv" Dec 09 21:20:24 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:24 volumio volumio[1234]: info: Discovery: this is already registered, 216d264a-ee98-46dd-a398-6c79b9da5587 Dec 09 21:20:24 volumio volumio[1234]: info: Discovery: Found device Volumio Dec 09 21:20:24 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Dec 09 21:20:24 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:25 volumio ntpd[3188]: Listen normally on 4 wlan0 169.254.69.48:123 Dec 09 21:20:25 volumio ntpd[3188]: new interface(s) found: waking up resolver Dec 09 21:20:28 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:28 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:28 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:28 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:28 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:28 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:28 volumio volumio[1234]: info: [1765311628637] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:28 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:28 volumio go-librespot[1638]: time="2025-12-09T21:20:28+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:28 volumio go-librespot[1638]: time="2025-12-09T21:20:28+01:00" level=debug msg="loading track spotify:track:7lf6EvTJtrbLKtJ1BKP3hv (paused: false, position: 0ms)" Dec 09 21:20:28 volumio go-librespot[1638]: time="2025-12-09T21:20:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:28 volumio go-librespot[1638]: time="2025-12-09T21:20:28+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:28 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7lf6EvTJtrbLKtJ1BKP3hv","play_origin":"go-librespot"}} Dec 09 21:20:28 volumio go-librespot[1638]: time="2025-12-09T21:20:28+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:7lf6EvTJtrbLKtJ1BKP3hv" Dec 09 21:20:28 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:29 volumio volumio[1234]: info: CoreCommandRouter::volumioPrevious Dec 09 21:20:29 volumio volumio[1234]: info: CoreStateMachine::previous Dec 09 21:20:29 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:29 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:29 volumio volumio[1234]: info: CoreStateMachine::pushState Dec 09 21:20:29 volumio volumio[1234]: info: CorePlayQueue::getTrack 6 Dec 09 21:20:29 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:20:29 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Dec 09 21:20:29 volumio volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:20:29 volumio volumio[1234]: info: MRS: Pushing multiroomSync output Dec 09 21:20:32 volumio volumio[1234]: info: MRS: STARTING volumioStreaming Dec 09 21:20:32 volumio sudo[3543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 09 21:20:32 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:32 volumio sudo[3546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Dec 09 21:20:32 volumio sudo[3543]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:32 volumio sudo[3546]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:32 volumio systemd[1]: Stopping VolumioStreamingService... Dec 09 21:20:32 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Dec 09 21:20:32 volumio volumio[1234]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 21:20:32 volumio systemd[1]: volumioStreaming.service: Succeeded. Dec 09 21:20:32 volumio systemd[1]: Stopped VolumioStreamingService. Dec 09 21:20:32 volumio volumio[1234]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 21:20:32 volumio systemd[1]: Started VolumioStreamingService. Dec 09 21:20:32 volumio sudo[3546]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:32 volumio volumio[1234]: info: MRS: volumioStreaming STARTED Dec 09 21:20:32 volumio sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 09 21:20:32 volumio sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:32 volumio sudo[3561]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:20:33 volumio sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:33 volumio sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:33 volumio sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:33 volumio sudo[3574]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio sudo[3581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:20:33 volumio sudo[3581]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:33 volumio sudo[3577]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio sudo[3581]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio sudo[3594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:33 volumio sudo[3602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 09 21:20:33 volumio sudo[3594]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:33 volumio sudo[3602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:33 volumio sudo[3594]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 09 21:20:33 volumio sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:33 volumio sudo[3602]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio sudo[3611]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:33 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:33 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:33 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 6 Dec 09 21:20:33 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 6 Dec 09 21:20:33 volumio volumio[1234]: info: [1765311633836] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:33 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:33 volumio volumio[1234]: info: Upmpdcli Daemon Started Dec 09 21:20:33 volumio volumio[1234]: info: Upmpdcli Daemon Started Dec 09 21:20:33 volumio go-librespot[1638]: time="2025-12-09T21:20:33+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:33 volumio go-librespot[1638]: time="2025-12-09T21:20:33+01:00" level=debug msg="loading track spotify:track:3NFs3XUduzBfvc5Bx1gmzh (paused: false, position: 0ms)" Dec 09 21:20:33 volumio go-librespot[1638]: time="2025-12-09T21:20:33+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:33 volumio go-librespot[1638]: time="2025-12-09T21:20:33+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:33 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3NFs3XUduzBfvc5Bx1gmzh","play_origin":"go-librespot"}} Dec 09 21:20:33 volumio go-librespot[1638]: time="2025-12-09T21:20:33+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:3NFs3XUduzBfvc5Bx1gmzh" Dec 09 21:20:33 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:35 volumio volumio[1234]: info: CoreCommandRouter::volumioSeek Dec 09 21:20:35 volumio volumio[1234]: info: CoreStateMachine::seek Dec 09 21:20:35 volumio volumio[1234]: info: CorePlayQueue::getTrack 6 Dec 09 21:20:35 volumio volumio[1234]: info: TRACKBLOCK {"uri":"spotify:track:3NFs3XUduzBfvc5Bx1gmzh","service":"spop","name":"The Dead Dance","artist":"Lady Gaga","album":"The Dead Dance","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8a389abdf9cb2d03b85ff54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Dec 09 21:20:35 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:35 volumio volumio[1234]: info: CorePlayQueue::getTrack 6 Dec 09 21:20:35 volumio volumio[1234]: info: Spotify seek to: 59000 Dec 09 21:20:35 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/seek Dec 09 21:20:35 volumio volumio[1234]: info: CoreStateMachine::pushState Dec 09 21:20:35 volumio volumio[1234]: info: CorePlayQueue::getTrack 6 Dec 09 21:20:35 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:20:35 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Dec 09 21:20:35 volumio volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:20:35 volumio volumio[1234]: info: MRS: Pushing multiroomSync output Dec 09 21:20:40 volumio volumio[1234]: info: CoreCommandRouter::volumioNext Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::next Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:40 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:40 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:40 volumio volumio[1234]: info: [1765311640583] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:40 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:40 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:40 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:40 volumio go-librespot[1638]: time="2025-12-09T21:20:40+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:40 volumio go-librespot[1638]: time="2025-12-09T21:20:40+01:00" level=debug msg="loading track spotify:track:7lf6EvTJtrbLKtJ1BKP3hv (paused: false, position: 0ms)" Dec 09 21:20:40 volumio go-librespot[1638]: time="2025-12-09T21:20:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:40 volumio go-librespot[1638]: time="2025-12-09T21:20:40+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:40 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7lf6EvTJtrbLKtJ1BKP3hv","play_origin":"go-librespot"}} Dec 09 21:20:40 volumio go-librespot[1638]: time="2025-12-09T21:20:40+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:7lf6EvTJtrbLKtJ1BKP3hv" Dec 09 21:20:40 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:41 volumio volumio[1234]: info: CoreCommandRouter::volumioRandom Dec 09 21:20:41 volumio volumio[1234]: info: CoreCommandRouter::writePlayerControls Dec 09 21:20:41 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:41 volumio volumio[1234]: info: CoreStateMachine::setRandom true Dec 09 21:20:41 volumio volumio[1234]: info: CoreStateMachine::pushState Dec 09 21:20:41 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:41 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:20:41 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Dec 09 21:20:41 volumio volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:20:41 volumio volumio[1234]: info: MRS: Pushing multiroomSync output Dec 09 21:20:43 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:20:43 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:43 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:43 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:43 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:43 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:43 volumio volumio[1234]: info: [1765311643384] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:43 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:43 volumio go-librespot[1638]: time="2025-12-09T21:20:43+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:43 volumio go-librespot[1638]: time="2025-12-09T21:20:43+01:00" level=debug msg="loading track spotify:track:7lf6EvTJtrbLKtJ1BKP3hv (paused: false, position: 0ms)" Dec 09 21:20:43 volumio go-librespot[1638]: time="2025-12-09T21:20:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:43 volumio go-librespot[1638]: time="2025-12-09T21:20:43+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:43 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7lf6EvTJtrbLKtJ1BKP3hv","play_origin":"go-librespot"}} Dec 09 21:20:43 volumio go-librespot[1638]: time="2025-12-09T21:20:43+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:7lf6EvTJtrbLKtJ1BKP3hv" Dec 09 21:20:43 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:44 volumio volumio[1234]: info: CoreCommandRouter::volumioRandom Dec 09 21:20:44 volumio volumio[1234]: info: CoreCommandRouter::writePlayerControls Dec 09 21:20:44 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:44 volumio volumio[1234]: info: CoreStateMachine::setRandom false Dec 09 21:20:44 volumio volumio[1234]: info: CoreStateMachine::pushState Dec 09 21:20:44 volumio volumio[1234]: info: CorePlayQueue::getTrack 7 Dec 09 21:20:44 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:20:44 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Dec 09 21:20:44 volumio volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:20:44 volumio volumio[1234]: info: MRS: Pushing multiroomSync output Dec 09 21:20:45 volumio volumio[1234]: info: CoreCommandRouter::volumioNext Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::next Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::stop Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:20:45 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:45 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:20:45 volumio volumio[1234]: info: [1765311645478] ControllerSpotify::clearAddPlayTrack Dec 09 21:20:45 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:20:45 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Dec 09 21:20:45 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Dec 09 21:20:45 volumio go-librespot[1638]: time="2025-12-09T21:20:45+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:20:45 volumio go-librespot[1638]: time="2025-12-09T21:20:45+01:00" level=debug msg="loading track spotify:track:6wHpLMmp98aHcV8L1JFrj8 (paused: false, position: 1ms)" Dec 09 21:20:45 volumio go-librespot[1638]: time="2025-12-09T21:20:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:20:45 volumio go-librespot[1638]: time="2025-12-09T21:20:45+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:20:45 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","play_origin":"go-librespot"}} Dec 09 21:20:45 volumio go-librespot[1638]: time="2025-12-09T21:20:45+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6wHpLMmp98aHcV8L1JFrj8" Dec 09 21:20:45 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:20:47 volumio volumio[1234]: info: CoreCommandRouter::volumioSeek Dec 09 21:20:47 volumio volumio[1234]: info: CoreStateMachine::seek Dec 09 21:20:47 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:20:47 volumio volumio[1234]: info: TRACKBLOCK {"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","service":"spop","name":"Eternity","artist":"Alex Warren","album":"You'll Be Alright, Kid","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0a2d7a2d8406a4a72a92d5c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Dec 09 21:20:47 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:20:47 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:20:47 volumio volumio[1234]: info: Spotify seek to: 87000 Dec 09 21:20:47 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/seek Dec 09 21:20:47 volumio volumio[1234]: info: CoreStateMachine::pushState Dec 09 21:20:47 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:20:47 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:20:47 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Dec 09 21:20:47 volumio volumio[1234]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:20:47 volumio volumio[1234]: info: MRS: Pushing multiroomSync output Dec 09 21:20:50 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:20:50 volumio volumio[1234]: info: In handleBrowseUri, curUri=spotify Dec 09 21:20:50 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:50 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:50 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:50 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:20:56 volumio sudo[3636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:20:56 volumio sudo[3636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:56 volumio sudo[3639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:20:56 volumio sudo[3636]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:56 volumio sudo[3639]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:20:56 volumio sudo[3639]: pam_unix(sudo:session): session closed for user root Dec 09 21:20:56 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 8 Dec 09 21:20:56 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Dec 09 21:20:56 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:20:56 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:20:56 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:20:56 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:20:56 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:20:56 volumio volumio[1234]: info: Listing playlists Dec 09 21:20:56 volumio volumio[1234]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:20:56 volumio volumio[1234]: info: CoreStateMachine::getQueue Dec 09 21:20:56 volumio volumio[1234]: info: CorePlayQueue::getQueue Dec 09 21:20:58 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:21:04 volumio sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:21:04 volumio sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:21:04 volumio sudo[3659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:21:04 volumio sudo[3656]: pam_unix(sudo:session): session closed for user root Dec 09 21:21:04 volumio sudo[3659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:21:04 volumio sudo[3659]: pam_unix(sudo:session): session closed for user root Dec 09 21:21:04 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 8 Dec 09 21:21:04 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Dec 09 21:21:04 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:04 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:21:04 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:21:04 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:21:04 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:21:04 volumio volumio[1234]: info: Listing playlists Dec 09 21:21:04 volumio volumio[1234]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:21:04 volumio volumio[1234]: info: CoreStateMachine::getQueue Dec 09 21:21:04 volumio volumio[1234]: info: CorePlayQueue::getQueue Dec 09 21:21:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:21:11 volumio volumio[1234]: info: In handleBrowseUri, curUri=spotify Dec 09 21:21:11 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:21:11 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:21:11 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:21:11 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:21:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:21:21 volumio volumio[1234]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXdvyLcddcaVU Dec 09 21:21:22 volumio volumio[1234]: info: Preload queue cleared Dec 09 21:21:25 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:21:25 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:21:25 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:21:25 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:25 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:21:25 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:25 volumio volumio[1234]: info: [1765311685974] ControllerSpotify::clearAddPlayTrack Dec 09 21:21:25 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:21:25 volumio go-librespot[1638]: time="2025-12-09T21:21:25+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:21:25 volumio go-librespot[1638]: time="2025-12-09T21:21:25+01:00" level=debug msg="loading track spotify:track:6wHpLMmp98aHcV8L1JFrj8 (paused: false, position: 1ms)" Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:21:26 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","play_origin":"go-librespot"}} Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6wHpLMmp98aHcV8L1JFrj8" Dec 09 21:21:26 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:21:26 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:21:26 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:21:26 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:21:26 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:26 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:21:26 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:26 volumio volumio[1234]: info: [1765311686590] ControllerSpotify::clearAddPlayTrack Dec 09 21:21:26 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=debug msg="loading track spotify:track:6wHpLMmp98aHcV8L1JFrj8 (paused: false, position: 0ms)" Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:21:26 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","play_origin":"go-librespot"}} Dec 09 21:21:26 volumio go-librespot[1638]: time="2025-12-09T21:21:26+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6wHpLMmp98aHcV8L1JFrj8" Dec 09 21:21:26 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:21:30 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 8 Dec 09 21:21:30 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Dec 09 21:21:30 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:30 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:21:30 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:21:30 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:21:30 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:21:30 volumio volumio[1234]: info: Listing playlists Dec 09 21:21:30 volumio volumio[1234]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:21:30 volumio volumio[1234]: info: CoreStateMachine::getQueue Dec 09 21:21:30 volumio volumio[1234]: info: CorePlayQueue::getQueue Dec 09 21:21:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 09 21:21:33 volumio volumio[1234]: info: Playing Remote Device: http://192.168.1.103 Dec 09 21:21:33 volumio volumio[1234]: info: Dec 09 21:21:33 volumio volumio[1234]: ---------------------------- Client requests Volumio play Dec 09 21:21:33 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:21:33 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:21:33 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:21:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:33 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:21:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:33 volumio volumio[1234]: info: [1765311693326] ControllerSpotify::clearAddPlayTrack Dec 09 21:21:33 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:21:33 volumio volumio[1234]: info: Done playing: Dec 09 21:21:33 volumio go-librespot[1638]: time="2025-12-09T21:21:33+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:21:33 volumio go-librespot[1638]: time="2025-12-09T21:21:33+01:00" level=debug msg="loading track spotify:track:6wHpLMmp98aHcV8L1JFrj8 (paused: false, position: 0ms)" Dec 09 21:21:33 volumio go-librespot[1638]: time="2025-12-09T21:21:33+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:21:33 volumio go-librespot[1638]: time="2025-12-09T21:21:33+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:21:33 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","play_origin":"go-librespot"}} Dec 09 21:21:33 volumio go-librespot[1638]: time="2025-12-09T21:21:33+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6wHpLMmp98aHcV8L1JFrj8" Dec 09 21:21:33 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 09 21:21:34 volumio volumio[1234]: info: Playing Remote Device: http://192.168.1.103 Dec 09 21:21:34 volumio volumio[1234]: info: Dec 09 21:21:34 volumio volumio[1234]: ---------------------------- Client requests Volumio play Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Dec 09 21:21:34 volumio volumio[1234]: info: CoreStateMachine::play index undefined Dec 09 21:21:34 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:21:34 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:34 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:21:34 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:34 volumio volumio[1234]: info: [1765311694204] ControllerSpotify::clearAddPlayTrack Dec 09 21:21:34 volumio volumio[1234]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:21:34 volumio volumio[1234]: info: Done playing: Dec 09 21:21:34 volumio go-librespot[1638]: time="2025-12-09T21:21:34+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:21:34 volumio go-librespot[1638]: time="2025-12-09T21:21:34+01:00" level=debug msg="loading track spotify:track:6wHpLMmp98aHcV8L1JFrj8 (paused: false, position: 0ms)" Dec 09 21:21:34 volumio go-librespot[1638]: time="2025-12-09T21:21:34+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:21:34 volumio go-librespot[1638]: time="2025-12-09T21:21:34+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:21:34 volumio volumio[1234]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","play_origin":"go-librespot"}} Dec 09 21:21:34 volumio go-librespot[1638]: time="2025-12-09T21:21:34+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:6wHpLMmp98aHcV8L1JFrj8" Dec 09 21:21:34 volumio volumio[1234]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:21:34 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 8 Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Dec 09 21:21:34 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:21:34 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:21:34 volumio volumio[1234]: info: Listing playlists Dec 09 21:21:34 volumio volumio[1234]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:21:34 volumio volumio[1234]: info: CoreStateMachine::getQueue Dec 09 21:21:34 volumio volumio[1234]: info: CorePlayQueue::getQueue Dec 09 21:21:36 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 8 Dec 09 21:21:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Dec 09 21:21:36 volumio volumio[1234]: info: CorePlayQueue::getTrack 8 Dec 09 21:21:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:21:36 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:21:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:21:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:21:36 volumio volumio[1234]: info: Listing playlists Dec 09 21:21:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:21:36 volumio volumio[1234]: info: CoreStateMachine::getQueue Dec 09 21:21:36 volumio volumio[1234]: info: CorePlayQueue::getQueue Dec 09 21:21:38 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 09 21:21:38 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 09 21:21:38 volumio volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 21:21:38 volumio volumio[1234]: TypeError: Cannot read property 'then' of undefined Dec 09 21:21:38 volumio volumio[1234]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:364:9) Dec 09 21:21:38 volumio volumio[1234]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2248:30) Dec 09 21:21:38 volumio volumio[1234]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1411:26) Dec 09 21:21:38 volumio volumio[1234]: at Socket.emit (events.js:400:28) Dec 09 21:21:38 volumio volumio[1234]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 09 21:21:38 volumio volumio[1234]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 09 21:21:38 volumio volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 21:21:38 volumio sudo[3685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 21:20 Dec 09 21:21:38 volumio sudo[3685]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:57:46 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="42c06b905df70635f414371e374efaf3"