Aug 26 17:07:02 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:02 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 26 17:07:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:02 volumio go-librespot[3236]: go-librespot daemon starting... Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="app state loaded" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=info msg="zeroconf server listening on port 44649" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="obtained new client token: AAEom3H97OAEl4ajljPCM9LfjGCX58vDThoq5QI3IX01fOkhxPhE1FvuXMnYkTDhfQNTzIy581OP9en1DCp0gZ/f7RwFKR0TeSME7wxbywkG6r4dD+Gc54KRbdybJ+u5GX27lHTIy9dnJ6DKuvFcDLa6eRvsYpOaudA7M4nohIkBJ+hu5Y4b4kW6ig2XIFo0azpKbI2VNAkrCRMmElsT0J19gBk+lYCVujLnOGWakFF8E932C80ZdSc=" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=debug msg="completed challenge" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:02 volumio go-librespot[3237]: time="2026-08-26T17:07:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:05 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 26 17:07:05 volumio volumio[1337]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:66FADfZzPWSwhRHKN7Tr11 Aug 26 17:07:05 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:05 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:05 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:05 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 17:07:05 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Aug 26 17:07:05 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:05 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:05 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Aug 26 17:07:05 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:05 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:05 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:05 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:05 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:05 volumio volumio[1337]: info: Adding Item to queue: spotify:user:spotify:playlist:66FADfZzPWSwhRHKN7Tr11 Aug 26 17:07:05 volumio volumio[1337]: info: Exploding uri spotify:user:spotify:playlist:66FADfZzPWSwhRHKN7Tr11 in service spop Aug 26 17:07:05 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:66FADfZzPWSwhRHKN7Tr11 Aug 26 17:07:06 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:06 volumio volumio[1337]: info: Preloading song: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 26 17:07:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:06 volumio go-librespot[3270]: go-librespot daemon starting... Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:2yTErvmzXrS0epevB7v25Q in service spop Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="app state loaded" Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:4TlYG3mKosVWfGbb9VfDh3 in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=info msg="zeroconf server listening on port 37433" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:026NnmyXjqOhorruAPY4DI in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="obtained new client token: AAHLFHCg753yPuBRpdOpanKYNMtP9+fRQNfC7TyiPrufLVSDaaF7MkDLc+FsPuZU0LDT4wrYNem0ujhWUOpMc3Jmm/HvTLsdc4X+4gYO+rkonUViKiB7QShbJWGIGjr5qK5pO5wj2QKdKbzw64BfqvnkPCV/RfeLwJiiH6AaG6D5fXilz0xalTZXFVjjhYikmMPNtg4BQwsZmD80+yLKD+IDt5yxC6qLsMc8YmfG59csBm4uwjGM" Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:0WyQ6226npyBKIU9ySa5e2 in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4TlYG3mKosVWfGbb9VfDh3","service":"spop","name":"Make You Feel That Way","artist":"Blackalicious","album":"Blazing Arrow","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c8df6078836c1dd3df5a19a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yTErvmzXrS0epevB7v25Q","service":"spop","name":"Punka","artist":"Kenickie","album":"At The Club","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf29a30104a128465ea66701","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=debug msg="completed challenge" Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:026NnmyXjqOhorruAPY4DI","service":"spop","name":"Upside Down","artist":"Carol Cool","album":"Best Of Jamaica","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b2738f5d628d196bb1c28a3100d7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:5eTaQYBE1yrActixMAeLcZ in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WyQ6226npyBKIU9ySa5e2","service":"spop","name":"Theme For Group A.D.","artist":"Group A.D.","album":"Theme For Group A.D.","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b27377c9276e3228f9c72d906734","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:47hpaV5rKqY7IOHbNacb2D in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:0F5INCGO2NuwmzUUaXv6WL in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:06 volumio go-librespot[3271]: time="2026-08-26T17:07:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5eTaQYBE1yrActixMAeLcZ","service":"spop","name":"Miracle (with Ellie Goulding)","artist":"Calvin Harris","album":"Miracle (with Ellie Goulding)","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273c58e22815048f8dfb1aa8bd0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:55lijDD6OAjLFFUHU9tcDm in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:77waSTIfZfnnrID2SRYp5J in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:47hpaV5rKqY7IOHbNacb2D","service":"spop","name":"The Bright Lights Of America","artist":"Anti-Flag","album":"The Bright Lights Of America","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b27395b1150d3734137f39966ad4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:55lijDD6OAjLFFUHU9tcDm","service":"spop","name":"WHERE IS MY HUSBAND!","artist":"RAYE","album":"WHERE IS MY HUSBAND!","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2736ac3c7938191585c53c8180d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:2mv6GY70UsHiTCGQZ4JfgC in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0F5INCGO2NuwmzUUaXv6WL","service":"spop","name":"Step Into A World (Rapture's Delight) - Radio Edit","artist":"KRS-One","album":"Step Into A World (Rapture's Delight) EP","type":"song","duration":274,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c8f95056db12d537291f857","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:06 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:06 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:06 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::play index 0 Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:06 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Aug 26 17:07:06 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:06 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Aug 26 17:07:06 volumio volumio[1337]: info: [1787760426586] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:06 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:06 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:77waSTIfZfnnrID2SRYp5J","service":"spop","name":"Something 4 the Weekend","artist":"Super Furry Animals","album":"Fuzzy Logic","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b27339a27c9892cc52b28bc1c1b1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:3ucVKI8BEpLXK3j64GDbY4 in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:6fzwardfFs6sVfNA5R1ypt in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:3Re0unscFRNOkwwEGtxhCO in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ucVKI8BEpLXK3j64GDbY4","service":"spop","name":"Again","artist":"Girl Tones","album":"Blame","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b273fff0639d59af4563fa31db66","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:3deMnlcpVp0FneW9DNCzWr in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:17076c3jEyuTYHYnMN6P1G in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:6Zgd7SomLTZkL1WPh4CUnV in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Re0unscFRNOkwwEGtxhCO","service":"spop","name":"Heaven - Acoustic","artist":"Grace George","album":"Heaven (Acoustic)","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b273524cafce42b3c3e3c425a492","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3deMnlcpVp0FneW9DNCzWr","service":"spop","name":"It Must Be Love","artist":"Labi Siffre","album":"Crying Laughing Loving Lying (Deluxe Edition)","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b2734f6f29e2eb6055e6451e042e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2mv6GY70UsHiTCGQZ4JfgC","service":"spop","name":"Fools Gold - Remastered 2009","artist":"The Stone Roses","album":"The Stone Roses","type":"song","duration":594,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf1f6466a493eb73d6d9d280","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:3iSEZZSF7P1TKesmTrWeFc in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:17076c3jEyuTYHYnMN6P1G","service":"spop","name":"Follow The Light","artist":"Broadcast","album":"Follow The Light","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b27354e1454f608b979cfe10fb93","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Zgd7SomLTZkL1WPh4CUnV","service":"spop","name":"Wolf Like Me","artist":"TV On The Radio","album":"Return To Cookie Mountain","type":"song","duration":287,"albumart":"https://i.scdn.co/image/ab67616d0000b273217de86bedd48dfaf5a05167","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:06 volumio volumio[1337]: info: Exploding uri spotify:track:7H3ojI1BsVy0dEJENqMt1k in service spop Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:06 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6fzwardfFs6sVfNA5R1ypt","service":"spop","name":"Valley of the Shadows","artist":"Origin Unknown","album":"Valley of the Shadows","type":"song","duration":280,"albumart":"https://i.scdn.co/image/ab67616d0000b2733682ab3a1f6ee66814393098","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:0ABgQEAsfdvwtOyCe113lt in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3iSEZZSF7P1TKesmTrWeFc","service":"spop","name":"Smokebelch II","artist":"Pete Tong","album":"Classic House","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b2730bafd41f8f1c1acb3ec2a96d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:3cWQRSutAjP6PY3kPzgi2b in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:73pvFUclavcMwuJnfYzsbH in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7H3ojI1BsVy0dEJENqMt1k","service":"spop","name":"By Your Side","artist":"Sade","album":"Lovers Rock","type":"song","duration":274,"albumart":"https://i.scdn.co/image/ab67616d0000b27320fc6951e4d293707de2434c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ABgQEAsfdvwtOyCe113lt","service":"spop","name":"Lights Burn Dimmer","artist":"Fred again..","album":"Lights Burn Dimmer","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273f890bbb2cc4c116ea0c8b87a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:0bFjFLAlwvmtmNIU0Hn8kI in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3cWQRSutAjP6PY3kPzgi2b","service":"spop","name":"Rock Music","artist":"Charli xcx","album":"Rock Music","type":"song","duration":115,"albumart":"https://i.scdn.co/image/ab67616d0000b273b7bc469a85354ff1ae7a1386","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:0NcaGfUreF2XI9DovFw2Kc in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:73pvFUclavcMwuJnfYzsbH","service":"spop","name":"Eve","artist":"VENUS GRRRLS","album":"Eve","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273302fce6a09465766c957e31e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bFjFLAlwvmtmNIU0Hn8kI","service":"spop","name":"Divine","artist":"VENUS GRRRLS","album":"Divine","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273a00f5281503d2da0ff05a14a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:6mPFZysBmfAlDI4hmdOt7o in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:3i8TRXOR9zN91ZSBtcsxlj in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:5tUlvCKbV6xyAI7MgFuB0p in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6mPFZysBmfAlDI4hmdOt7o","service":"spop","name":"Go (Crazy Cousinz Remix) [Radio Edit]","artist":"Malika","album":"Go (Now It's Over Boy)","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b27319c14d1a92cc8826e7cf46a2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:6SvlfrQYzUsW5UQUpUpy26 in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5tUlvCKbV6xyAI7MgFuB0p","service":"spop","name":"Pianos Raining Down","artist":"4am Kru","album":"Pianos Raining Down","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b27361953e2dd5574cf6f1bd3aa5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:3G2VHJHk8OZu3ApFOl5QoX in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0NcaGfUreF2XI9DovFw2Kc","service":"spop","name":"Just The Two Of Us (feat. Kasey Karlsen of Deadlands)","artist":"Tabloid Nation","album":"Just The Two Of Us (feat. Kasey Karlsen of Deadlands)","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b273dcf2d9a93f6657392c2b4693","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:23SqhGYsqaZJNbDSc17wqA in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:0XyBzQ0hOpfmXSjG2CwIqk in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3i8TRXOR9zN91ZSBtcsxlj","service":"spop","name":"I Guess Time Just Makes Fools of Us All","artist":"Father John Misty","album":"I Guess Time Just Makes Fools of Us All","type":"song","duration":515,"albumart":"https://i.scdn.co/image/ab67616d0000b273e3013692c45c3874cc978a56","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3G2VHJHk8OZu3ApFOl5QoX","service":"spop","name":"Run Down","artist":"Ghost Coach","album":"Recall","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b27380a778b5ea3db60e7952d66f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:5kje42RAjDsJlKpN4OezWL in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6SvlfrQYzUsW5UQUpUpy26","service":"spop","name":"Bangaranga","artist":"DARA","album":"Bangaranga","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273ee6ff14d338a2af1edc2f5d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:23SqhGYsqaZJNbDSc17wqA","service":"spop","name":"The Sun Will Never Settle","artist":"Kingfishr","album":"The Sun Will Never Settle","type":"song","duration":148,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c5d30b6a176e31da32e98a5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:5zjUGYISkPpwfCKyaWkHBD in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0XyBzQ0hOpfmXSjG2CwIqk","service":"spop","name":"Heaven Hanging Low","artist":"Art School Girlfriend","album":"Heaven Hanging Low","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b2730ce11a165b91f2d6e84a1f4e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5kje42RAjDsJlKpN4OezWL","service":"spop","name":"Holy","artist":"Dave Lofts","album":"Holy","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b273aad2bf3967e9d6bb9c8213b7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:278TZTqtqRUpsJPUQAzYy0 in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5zjUGYISkPpwfCKyaWkHBD","service":"spop","name":"Paper Doll - Live","artist":"Samantha Fish","album":"Paper Doll (Live)","type":"song","duration":256,"albumart":"https://i.scdn.co/image/ab67616d0000b273bc1956daf273bd8b5533f8c0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:38tpcZDofjtDNunMm5w1EU in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:67IDCzanosZIHyC0ogmVcA in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:278TZTqtqRUpsJPUQAzYy0","service":"spop","name":"Trains To Brazil","artist":"Guillemots","album":"Through The Windowpane","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273142df9d1869881969422a915","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:38tpcZDofjtDNunMm5w1EU","service":"spop","name":"If I Could Change Your Mind","artist":"HAIM","album":"Days Are Gone (Deluxe Edition)","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b2735841140a46549b7e95202b9f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:1S4Bdtk3PSLDfCfeqfmzvo in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:67IDCzanosZIHyC0ogmVcA","service":"spop","name":"Deceptacon","artist":"Le Tigre","album":"Le Tigre","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b2731eaf34183a0447930df1bb15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:07 volumio volumio[1337]: info: Exploding uri spotify:track:5b7bnzBT8F7SMg5nwOolbk in service spop Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:07 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GMeW8uDI1ZJsCPFvdk1T1","service":"spop","name":"BACK 2 LIFE","artist":"Sigma","album":"BACK 2 LIFE","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273a4a33c890591183fd136aa0b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:1EETPP3SQYvstBJExeOd1S in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1S4Bdtk3PSLDfCfeqfmzvo","service":"spop","name":"Jolene","artist":"Leila Forouhar","album":"Do Parandeh","type":"song","duration":221,"albumart":"https://i.scdn.co/image/ab67616d0000b273a8967c26ec80dc23899a5e15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:4cErKi16sjFZOfd85t9dnt in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1EETPP3SQYvstBJExeOd1S","service":"spop","name":"Run With Us","artist":"The Raccoons","album":"Evergreen Nights","type":"song","duration":267,"albumart":"https://i.scdn.co/image/ab67616d0000b273c073bb7c3fb6f08c0ce7d889","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5b7bnzBT8F7SMg5nwOolbk","service":"spop","name":"Rivers Of Babylon","artist":"The Melodians","album":"The Harder They Come (Original Motion Picture Soundtrack)","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b2736033eb0ca150f7d26d897581","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:7rEiFVf9fu4mwuQSCHrEiI in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:31QMfRBwxI8zm0fAevhhcg in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:65uoaqX5qcjXZRheAj1qQT in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7rEiFVf9fu4mwuQSCHrEiI","service":"spop","name":"You Held the World in Your Arms","artist":"Idlewild","album":"The Remote Part","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b273b99af0e2c372ff524c92db5c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:734dz1YaFITwawPpM25fSt in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4cErKi16sjFZOfd85t9dnt","service":"spop","name":"CRUISING TO SELF SOOTHE","artist":"Ecca Vandal","album":"LOOKING FOR PEOPLE TO UNFOLLOW","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d8195570025f97b289bf2f7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:31QMfRBwxI8zm0fAevhhcg","service":"spop","name":"The Situation","artist":"Otto & Astrid - Die Roten Punkte","album":"Kunst Rock (Art Rock)","type":"song","duration":28,"albumart":"https://i.scdn.co/image/ab67616d0000b273c2736dd040a01d2265f2c812","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:7GsqCRpYL09jG96OIGOg0O in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:65uoaqX5qcjXZRheAj1qQT","service":"spop","name":"Dernière danse","artist":"Indila","album":"Mini World","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ae8ff731c49965bf2083405","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:506FIFl6Owe3tjXcEpcukn in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:734dz1YaFITwawPpM25fSt","service":"spop","name":"Je veux","artist":"Zaz","album":"Zaz","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b2733b5969aba116e8248a3e6369","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7GsqCRpYL09jG96OIGOg0O","service":"spop","name":"The Machine","artist":"Samantha Togni","album":"The Meaningless Beautiful","type":"song","duration":267,"albumart":"https://i.scdn.co/image/ab67616d0000b273fba9aa34d45c0a3029d8ceb4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:1Jy8kD72Pk84ICD44Funni in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Ne3Z7QaIv96tGyGwkYpN5","service":"spop","name":"Outro","artist":"YUNIQUE.","album":"Outro","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c15abc52cdbb254792a2cdf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:506FIFl6Owe3tjXcEpcukn","service":"spop","name":"Halfway To Everywhere","artist":"The Parachute Testers","album":"Halfway To Everywhere","type":"song","duration":289,"albumart":"https://i.scdn.co/image/ab67616d0000b2739a186a9994fe9a2207f119f8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Exploding uri spotify:track:2SNHn6cmgT0MHLimGXZtGz in service spop Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Jy8kD72Pk84ICD44Funni","service":"spop","name":"Imigradan","artist":"Les Filles de Illighadad","album":"Eghass Malan","type":"song","duration":306,"albumart":"https://i.scdn.co/image/ab67616d0000b273ca375c3ed27d87173820bbba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:08 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:08 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:08 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2SNHn6cmgT0MHLimGXZtGz","service":"spop","name":"Faya","artist":"Joe Driscoll","album":"Faya","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273f71c35e0c4399123222437b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 26 17:07:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 26 17:07:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:09 volumio go-librespot[3287]: go-librespot daemon starting... Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="app state loaded" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=info msg="zeroconf server listening on port 38691" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="obtained new client token: AAHhL/RTkj/WA66sqqSKEsdrmKD0Dsn0GIpt8sr3cnjVWNeLEF8Q5Av9LzfGXMgdhq9+Wq+2+EL+6qzQDX5lxZOOyqOcpSTSC0sLqP2qltHB7hdSAT3iru29V5djqfcb5Thm4/qcjf4Men0/ikMqUePwuklDC6xbz9S359WB6Yqq+3me1iFd8favMdadWbSoini4HjDR22yA6lQBzq3J9oip8iGzWMeixjTN2g2E7jyzBRG0jNs08+0=" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=debug msg="completed challenge" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:09 volumio go-librespot[3288]: time="2026-08-26T17:07:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:11 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:11 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:11 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:11 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:11 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:11 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::play index 36 Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:11 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:11 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:11 volumio volumio[1337]: info: Using cached record of: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:11 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::getTrack 36 Aug 26 17:07:11 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:11 volumio volumio[1337]: info: CorePlayQueue::getTrack 36 Aug 26 17:07:11 volumio volumio[1337]: info: [1787760431367] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:11 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:11 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:11 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:11 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 26 17:07:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:13 volumio go-librespot[3303]: go-librespot daemon starting... Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="app state loaded" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=info msg="zeroconf server listening on port 44217" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="obtained new client token: AAElxWqzzr7DEI3gSJbd9q3XKuQFt076+SPu50zBOlfGPKDFBST/5Etj8i2UI6IXBUbaS5BKgAXAgHUXlL25mdBjefT5jZGh2ouFZRB2HQtAFj7+wVtQB6vDLWqxSf2nRoOzaSXgSJPYA0J3bWMJFuO2zuSN5oeavuy6Rea6sXsjMfRj3zoZ2Hv86Gch1XNaGBBGCxozDhZnA4r5EBSqkTxQEmezP1YX2rtGFamggK2QHOzz0Mfi" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=debug msg="completed challenge" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:13 volumio go-librespot[3304]: time="2026-08-26T17:07:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:13 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:13 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:13 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:13 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:13 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:13 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::play index 37 Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:13 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:13 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:13 volumio volumio[1337]: info: Using cached record of: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:13 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:13 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:13 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:13 volumio volumio[1337]: info: [1787760433990] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:13 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:13 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:14 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:14 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 26 17:07:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:16 volumio go-librespot[3334]: go-librespot daemon starting... Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="app state loaded" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=info msg="zeroconf server listening on port 33285" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="obtained new client token: AAEQz2YWiBPotepAzNw0MOe/CB8qANzok+aMso9gQbq3/guKuoOr6a8CLvL7/khj3P8EHhpfw/9MM0fd2y2d99u1WjCLRdB/TaCmxIwgxbYUPcpeTA/6xCjNomS0P0x0yXHqTQzlyv7/36oRQqSnPdeSF7s28THnBEfqWhU1F9DJJDAFBt8T0pNcNRcscupJqR5LfbO1TEvOjyw0a8fX2dopAsmasSM2eof6TT44/Ou6O5y7EDdSIr0=" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=debug msg="completed challenge" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:16 volumio go-librespot[3335]: time="2026-08-26T17:07:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:17 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:17 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 26 17:07:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:20 volumio go-librespot[3354]: go-librespot daemon starting... Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="app state loaded" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=info msg="zeroconf server listening on port 44861" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="obtained new client token: AAGtSVmsUavifCmZYXny3rafz180sOcFbYszwuJAU8PXDVNUkI6+4kVcE6g67mdLGJErspTPrXbKOMraJe1kPCHtWcsW9edm0F01N+vOzWlumtoK8T11pH5CpYIyrpyKtA2w5u+0LxGPbhqvuzub1LrvaK8Ni6SQGZSFR0BJXiK7nOXz/venGDIsulifxN2IWyJbRt5ig2B/x5X8Sbs9g6bVb8XJwrbQhFFryRc2g2UH8mGzfsbs" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=debug msg="completed challenge" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:20 volumio go-librespot[3355]: time="2026-08-26T17:07:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:20 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:20 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:22 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::play index 40 Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:07:22 volumio volumio[1337]: info: [1787760442384] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:22 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:22 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:22 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2yTErvmzXrS0epevB7v25Q Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:4TlYG3mKosVWfGbb9VfDh3 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:026NnmyXjqOhorruAPY4DI Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0WyQ6226npyBKIU9ySa5e2 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5eTaQYBE1yrActixMAeLcZ Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:47hpaV5rKqY7IOHbNacb2D Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0F5INCGO2NuwmzUUaXv6WL Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:77waSTIfZfnnrID2SRYp5J Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2mv6GY70UsHiTCGQZ4JfgC Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3ucVKI8BEpLXK3j64GDbY4 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6fzwardfFs6sVfNA5R1ypt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3Re0unscFRNOkwwEGtxhCO Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3deMnlcpVp0FneW9DNCzWr Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:17076c3jEyuTYHYnMN6P1G Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6Zgd7SomLTZkL1WPh4CUnV Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3iSEZZSF7P1TKesmTrWeFc Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:7H3ojI1BsVy0dEJENqMt1k Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0ABgQEAsfdvwtOyCe113lt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3cWQRSutAjP6PY3kPzgi2b Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:73pvFUclavcMwuJnfYzsbH Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0bFjFLAlwvmtmNIU0Hn8kI Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0NcaGfUreF2XI9DovFw2Kc Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6mPFZysBmfAlDI4hmdOt7o Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3i8TRXOR9zN91ZSBtcsxlj Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5tUlvCKbV6xyAI7MgFuB0p Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:6SvlfrQYzUsW5UQUpUpy26 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:3G2VHJHk8OZu3ApFOl5QoX Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:23SqhGYsqaZJNbDSc17wqA Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0XyBzQ0hOpfmXSjG2CwIqk Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5kje42RAjDsJlKpN4OezWL Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5zjUGYISkPpwfCKyaWkHBD Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:278TZTqtqRUpsJPUQAzYy0 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:38tpcZDofjtDNunMm5w1EU Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:67IDCzanosZIHyC0ogmVcA Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:0GMeW8uDI1ZJsCPFvdk1T1 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:1S4Bdtk3PSLDfCfeqfmzvo Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:5b7bnzBT8F7SMg5nwOolbk Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:1EETPP3SQYvstBJExeOd1S Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:4cErKi16sjFZOfd85t9dnt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:7rEiFVf9fu4mwuQSCHrEiI Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::play index 40 Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Aug 26 17:07:22 volumio volumio[1337]: info: Preload queue cleared Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:31QMfRBwxI8zm0fAevhhcg Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:65uoaqX5qcjXZRheAj1qQT Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:734dz1YaFITwawPpM25fSt Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:7GsqCRpYL09jG96OIGOg0O Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:506FIFl6Owe3tjXcEpcukn Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2Ne3Z7QaIv96tGyGwkYpN5 Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:1Jy8kD72Pk84ICD44Funni Aug 26 17:07:22 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:22 volumio volumio[1337]: info: Using cached record of: spotify:track:2SNHn6cmgT0MHLimGXZtGz Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::saveQueue Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:07:22 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:22 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:07:22 volumio volumio[1337]: info: [1787760442561] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:22 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:22 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:23 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:23 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 26 17:07:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:23 volumio go-librespot[3371]: go-librespot daemon starting... Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="app state loaded" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=info msg="zeroconf server listening on port 45313" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="obtained new client token: AAHcjJ4ljcXZz9K2hWaYkaAOwNtjK4+93Jttc/Buk5HzZOOk/WzrDX3LrSPqZJQyEnENJusOn4RrOEn+i2b1Kw0JqloecQTYubYhrs3lCc5w5eWxiLlGZG9uHLXxWVPiV7woiCLxhxLQQoLOrRsO3nBlVq1fWxVzpEpWfcl+0OpR2nLpw+hU8yx4ONfqI8h70mnGD81DOyehV7Ouu8k7U5bFhQgSNEtUqA4PzcnJtxwkxjqjCbAcAos=" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=debug msg="completed challenge" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:23 volumio go-librespot[3372]: time="2026-08-26T17:07:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:26 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:26 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 26 17:07:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:27 volumio go-librespot[3403]: go-librespot daemon starting... Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="app state loaded" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=info msg="zeroconf server listening on port 39517" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="obtained new client token: AAFYZfkn8Lj+X6it3e6U+gcS6a7VSgEnOSRB9tpiPqTcZP8EdwnpI/kK2F7ax7PM1VYxcYsi8Qfp5/EgLsH1qCh0/RCwG/yPaEzNcZSY//qC5VzkLDc1WWzIgi4PeiTPr/skeM3VbebvXfbOUWm/yPenPf72wHfkUMSvoU3fyrcGFB0WFOiAbVtpGCPwgrEBW9f2YmKoEsXbGRrupmKPKUO9S1jhIRYguSSq2LGyElmTGGTfERrk" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=debug msg="completed challenge" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:27 volumio go-librespot[3404]: time="2026-08-26T17:07:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:29 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:29 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 26 17:07:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:30 volumio go-librespot[3420]: go-librespot daemon starting... Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="app state loaded" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=info msg="zeroconf server listening on port 38929" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="obtained new client token: AAHdt87yE3okHwAYhygagGXcVH/vjfP1e8hIFuXkmAyVvlDhvDRrfX0va5r0Z0Pjr9UEprRqZuIycZVEIHNwT0/1Ro+/EBSuGSXk1nQa8l5vXz3SAOTWJgouI7WSN4U9elAUYWKwXdY0AfYK0lqeOG2a+aDUsipSJ3xL9DUZpLXdf1nyPioKug1MwV/X2gDvvDZfY5POFSH6vNRk7GbBbAWXmYghnrSm5fvTqIXTV7MiuWB6y1Ggye0=" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=debug msg="completed challenge" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:30 volumio go-librespot[3421]: time="2026-08-26T17:07:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:32 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:32 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 26 17:07:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:34 volumio go-librespot[3438]: go-librespot daemon starting... Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="app state loaded" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=info msg="zeroconf server listening on port 43917" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="obtained new client token: AAHOBDLvXmsil+0NtMnTgpywQ09e5j1FOMqmiahZ5VTV++ySjsvJFJMRsxGErWAfMZRWPhV27D7hnANzZu0BgYeANM1r28uMZFscUjL6h/SJwRsgos2K+gql9HTzXVCE3wVtY6dzwq9EfjIHjXy87Db6hyCIRaTded81yurRaTbHZTEGy6eZM6Y4uguTPsYplyi34JKv8rm71G7OLvJdNK92rpvWibhKY3LZEor7HsHDyNBB2oM5TF0=" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=debug msg="completed challenge" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:34 volumio go-librespot[3439]: time="2026-08-26T17:07:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:35 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:35 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Aug 26 17:07:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:37 volumio go-librespot[3469]: go-librespot daemon starting... Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="app state loaded" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=info msg="zeroconf server listening on port 41503" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="obtained new client token: AAHJDDmkgNaK/wCYpUmRIRhs+W2so0ItrZ1qmBrsYJH5+wbGrVT3RnDH/3IVnNbAkPFK4odnkWLsMRXHqp3Q4DkUthfBqBjxWy/XWPIUIEc3Dm7F3VYlBlalfU2yOhhGdCM3UmP5Z1W28YhARqIMO9QePok9R1I8G326AZvq00cd1xX4veyZMhO6Mwnw5dv4cMsuo8s4iTqbnXyDW12MyBlWdYPo4Se+1171U6EG3KBvpoI3hFL49+M=" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=debug msg="completed challenge" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:37 volumio go-librespot[3470]: time="2026-08-26T17:07:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:38 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:38 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Aug 26 17:07:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:41 volumio go-librespot[3486]: go-librespot daemon starting... Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="app state loaded" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=info msg="zeroconf server listening on port 35469" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="obtained new client token: AAFIS7U/e/08c0GGxa/qddqVVk+go6XuxuVBYJ6LEWDh0ifFX15bmulRPp/kKpZXeu6A0LOwHcOHtybIYB6ZRDJryJ4YzNv4Ta9Q2Ccb0ukC5GVcTeAamLO+8dZiAjIQMlnA8HfNuNiRbGznw2MoVw8SDA2FMq4aG6CGejI+/yFRilwd0Tt6xoxQcLgsT74+IxxyNUaIyiu0tW/eMT9wdaWzbNy4J48FdJecOU8TzfX06INfHqQQ" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=debug msg="completed challenge" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:41 volumio go-librespot[3487]: time="2026-08-26T17:07:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:41 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:41 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:44 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:44 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Aug 26 17:07:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:44 volumio go-librespot[3503]: go-librespot daemon starting... Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="app state loaded" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=info msg="zeroconf server listening on port 35021" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="obtained new client token: AAFQKDQajlVO7IFHB86PzqjA9wYSWzm/6kRMOV1ePEXpxaPG6qyPk0moJ9LUMwkK6ywBhzKcYwOLq02xu9ZJsa63wU0Q+60O8D7hly1aeQbGW7KBpYasXq9ZdNXTeMyp45CgEyL5yJZmc41GXwjts5G8H5i+SRuiSQh9IqbYT7Iw5eBsGhpRvXEcvJj3lp+VowQUlTL1Cn+D2+Fxf0TWD2pDlY8IDdohUVGjSYwucHVKWeZ2HV5G0cI=" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=debug msg="completed challenge" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:44 volumio go-librespot[3504]: time="2026-08-26T17:07:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:47 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:47 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Aug 26 17:07:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:48 volumio go-librespot[3534]: go-librespot daemon starting... Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="app state loaded" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=info msg="zeroconf server listening on port 33815" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="obtained new client token: AAE5M5ZssrdOoV3uuNrVZhk2MNkR+9E/uAs5ygO6DXE8oJNGg9udHPHSpYoJZc14wtz9EdRTA2zqGAKvXPt33G24vP1fRsb0El8vsC31ALC2jdQKTfbHOvNovdfhxgSmm8nNlO/x8gBpFADRAUT214uiKdFs1vQ2XcDxyGxJNS1bZBvT7nICLntbb39Q4rDo6k5MLDAbwC8b+oSsOcWLbAR8t/k1YTZzCWld/vORYO3dYh80RWXC" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=debug msg="completed challenge" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:48 volumio go-librespot[3535]: time="2026-08-26T17:07:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:50 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:50 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:50 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::play index 37 Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:50 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:50 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:50 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:50 volumio volumio[1337]: info: [1787760470846] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:50 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:50 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Aug 26 17:07:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:51 volumio go-librespot[3554]: go-librespot daemon starting... Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="app state loaded" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=info msg="zeroconf server listening on port 45971" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:51 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::play index 37 Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:51 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:51 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:51 volumio volumio[1337]: info: [1787760471758] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:51 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="obtained new client token: AAHpwK8hYFGeyxP0WCDrG2W5evHHk9ifSYT6USEW/DZrDwRSvJIKnZA3wxhNVvQjN06NeYBwdOU0JJAgYIaoK5lg2u9aydWyDqjCV0y/8CGWCwsN0wxU5bCcdnNlNIdkJIB5Oq3ZHv7uixH+ARDVmBY5Tox+sEOXZbmD11ESWy4GG2Na2OqrXoXlxBg1lktcotGFdPlEoOZdnQ/lhhAO6XhP4IyJmmjOvHLe7Ajohd6uffUWL20E3Ws=" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=debug msg="completed challenge" Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:51 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::play index 37 Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:51 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:51 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:51 volumio volumio[1337]: info: CorePlayQueue::getTrack 37 Aug 26 17:07:51 volumio volumio[1337]: info: [1787760471925] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:51 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:51 volumio go-librespot[3555]: time="2026-08-26T17:07:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:51 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Aug 26 17:07:51 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Aug 26 17:07:53 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:53 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:54 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::play index 40 Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::stop Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:07:54 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:07:54 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:07:54 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:07:54 volumio volumio[1337]: info: [1787760474991] ControllerSpotify::clearAddPlayTrack Aug 26 17:07:54 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:07:54 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Aug 26 17:07:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:55 volumio go-librespot[3569]: go-librespot daemon starting... Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="app state loaded" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=info msg="zeroconf server listening on port 34657" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="obtained new client token: AAFsWe9QVeeiyx03tGlBqk7ZNM5VNZrazn5BplG5ZtnRz0zV7PXiaQ2JzSM8VYDecLxEKwvJaUNKhvIQD3lD2QN4I5fCf6wdNwEZvqwnZRATihBgZvfm7P6ikTWyVQdYTS5kySuDz+JqmiQH0xEDmIw6jjQ/r58nk2t9HW2HxQpFbILw0sllRa+l6rKNvUrTAoe5oOgRGi2hTReeqD0s5X6I9XNLRMzScxYTL1TvJqhCV3a4VblL" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=debug msg="completed challenge" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:55 volumio go-librespot[3570]: time="2026-08-26T17:07:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:56 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:56 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:07:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Aug 26 17:07:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:07:58 volumio go-librespot[3600]: go-librespot daemon starting... Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="app state loaded" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=info msg="zeroconf server listening on port 39221" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="obtained new client token: AAFwrnkjgNCA+Q907txEbsqvDSUrpruEKxUMXW+Jufm8a9KJ0RtA+ghs2Hmxb8mNAJaP3ieUXekcuDtVSSH+Alz+xnKLcNUoW2hXghAPYGXRPORmJ9vlp46SLNXZBV+4z5LCFmttNSJPmzJJGt9Qaqo8XF0xdXjXCluaEQvlt8T/z1LViMFFLvgzVNmrSZkMBytWsNCm6ilgd2TqsbbXGTp1yHe5WDfaI5PDDsJkui+WnHeHpKpJvWg=" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="completed keyexchange" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=debug msg="completed challenge" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:07:58 volumio go-librespot[3601]: time="2026-08-26T17:07:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:07:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:07:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:07:59 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:07:59 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Aug 26 17:08:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:02 volumio go-librespot[3616]: go-librespot daemon starting... Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="app state loaded" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=info msg="zeroconf server listening on port 42519" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="obtained new client token: AAHSPN6dDeivBxwWxJAxRLg5rlzw/9jo77ms4EQ8uapqkeU9j4TfyG/UIquEiTGEOhnBTHe2nNsh2cia937kT3O9K6WJYCZMNqdH97NCZrVfUKYQixaMGxAkP6ixUab2iYoSbper0Zl8R36AWD017JmRd75+/JkjkHfxltapdmdiru74ZAPPWBvNsVd6RPvU64e16dj3uDC9OgzFLtMaIECwzAKxQdQArGUK5dNns1Ku7XwQBEWt" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=debug msg="completed challenge" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:02 volumio go-librespot[3617]: time="2026-08-26T17:08:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:02 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:02 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:05 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:05 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Aug 26 17:08:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:05 volumio go-librespot[3637]: go-librespot daemon starting... Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="app state loaded" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=info msg="zeroconf server listening on port 43941" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="obtained new client token: AAEdao/WA+7qkVE/bPd8AAwaV9+g7jeGNHob2lJnb/AvRjF+00q+3UuHxbIydwMr/o/ve3mH6LB+SDVMZYwnEg3FEoCrrohSQ9bKOSSyj/1iGsdDPS+w86W6In3pBYTA7RKyAj+fcONr7GK2ut3umWMdSBFEVfclzpKCsPesq+nU+JPbxSllOcruRWqkI/7nFUvfo1d4Ka6yWGJQMcIDlpooE3hpRqnNHg66aBAAyzqI/R4Z521g6Cc=" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=debug msg="completed challenge" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:05 volumio go-librespot[3638]: time="2026-08-26T17:08:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:06 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Aug 26 17:08:06 volumio volumio[1337]: info: CoreStateMachine::play index undefined Aug 26 17:08:06 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:08:06 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:08:06 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:08:06 volumio volumio[1337]: info: CorePlayQueue::getTrack 40 Aug 26 17:08:06 volumio volumio[1337]: info: [1787760486982] ControllerSpotify::clearAddPlayTrack Aug 26 17:08:06 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Aug 26 17:08:06 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:08 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:08 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Aug 26 17:08:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:09 volumio go-librespot[3666]: go-librespot daemon starting... Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="app state loaded" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=info msg="zeroconf server listening on port 45899" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="obtained new client token: AAFN7VibzX4V10WyMz41TF9aeMLWzSYGDt/zOyDzeUS9tRfLiJIWJzi3WA87W1y++oLfSInQYgAbj+CxiQdhVjS1trKgoMy+DdVIhdJVtDKWv8cLOqsAlaZEvJUXw4x1pS2HfWiqEsAW5JooCRSOP4gzsHzNYf4EheyDuax9fszpeaTWAPfOMWPPtMBeb1zPsN/o6m33uyLB8Ke7nitOg5G4YPvepe0eoR+GrJF8vm7/q/p//Ngq" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=debug msg="completed challenge" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:09 volumio go-librespot[3667]: time="2026-08-26T17:08:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 17:08:09 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 17:08:11 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:11 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Aug 26 17:08:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:12 volumio go-librespot[3689]: go-librespot daemon starting... Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="app state loaded" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=info msg="zeroconf server listening on port 37855" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="obtained new client token: AAEFbjwArsyxEYpC6g0OGqF7FivNtJWP63uqKYLO7Tz14Rcc7epMV0rIeqVntjtZcqqwX/606OV6cTUugJO22tr2c4fAsl1uY86Zl7+BoB7vR7QSFjwin9U4/b7/1SWkRyDBzG/GwzYK5FqNBuF6sJh0A/yOSpdlROuzPkEnVFG+YxXf0q0MtX8VmXjiIQJjDs17ZchpuQO75pLbKaKdCCEGTWXMeO2xIFYfnYMh3V1Jhh2PifhY74I=" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=debug msg="completed challenge" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:12 volumio go-librespot[3690]: time="2026-08-26T17:08:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:14 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:14 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Aug 26 17:08:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:16 volumio go-librespot[3719]: go-librespot daemon starting... Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="app state loaded" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=info msg="zeroconf server listening on port 42775" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="obtained new client token: AAG2McpkA6bE62YhsstqooBaVazpBAJh1D6I2aD5j2tpM33hUX+fsbDVO7iHaNw6aotAUwSLscs84M01U32MvOjjAqOSA1EyT/xR+eYmlTc58+ux5A+3S5tJxeUJ7DyYmmcXJhC7Cp46tShVIofBchmsMdlz3N7kQ+2YGuwNCzoAQx6Z9G18nefDzSgAd6sIwRJSJ1d2DYdU/MlooEGhvd66Pv0AOxc4S8xFeX/gPza45kMaDIdfbLE=" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=debug msg="completed challenge" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:16 volumio go-librespot[3720]: time="2026-08-26T17:08:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:17 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:17 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Aug 26 17:08:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:19 volumio go-librespot[3741]: go-librespot daemon starting... Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="app state loaded" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=info msg="zeroconf server listening on port 35017" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="obtained new client token: AAGFKkEvHx6svTkEjEGJX0o28F6HAyg0BagUk/yxXeQ7QlnPFb5Ycf7pfwnZogD7X2B4KLixygj22cQo/nVSzfv5fglLs2gbgpqOAu3mQPRd8CbGGRcc9EgiRq9HWB+pLN0NjfkV8zRbaq9yT2z56seDe7b3aGR4S8CM7VGsPRIRwd1fjI5hezTdjOS7UM3Et6d4j4VgjVQVkBJ5Ss5UZIHmJZCU86as4ipaTV8jxuLJKRMVAUy9l7Q=" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=debug msg="completed challenge" Aug 26 17:08:19 volumio go-librespot[3742]: time="2026-08-26T17:08:19+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:20 volumio go-librespot[3742]: time="2026-08-26T17:08:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:20 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:20 volumio volumio[1337]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Aug 26 17:08:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:08:23 volumio go-librespot[3759]: go-librespot daemon starting... Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="app state loaded" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=info msg="zeroconf server listening on port 33485" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="obtained new client token: AAF7//ZJ2IVoDHURUHHbjzxHpJj14cibhmH1V5L1iWx8CwVJJIy1l5fSDgTCt3ECB3Dh8t7txmnx4a3SGCY1Tx5Gk7zbZU685n4XAAxf1w671AB3XzMirYJ6VnuxC2A9c9/FYQ+shnlKRtO4N7PlYgW2QxicfTHg/sfU7VVFrqZqRuX17HZSpn/mbzIECqOEjv6DR3CaRTvW2+ICPCYHnpidTG4TEatZdqlyzAYlJ2l53HNS0L8I" Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:08:23 volumio volumio[1337]: info: Initializing connection to go-librespot Websocket Aug 26 17:08:23 volumio go-librespot[3760]: time="2026-08-26T17:08:23+01:00" level=debug msg="new websocket client" Aug 26 17:08:23 volumio volumio[1337]: info: Connection to go-librespot Websocket established Aug 26 17:08:24 volumio go-librespot[3760]: time="2026-08-26T17:08:24+01:00" level=debug msg="completed keyexchange" Aug 26 17:08:24 volumio go-librespot[3760]: time="2026-08-26T17:08:24+01:00" level=debug msg="completed challenge" Aug 26 17:08:24 volumio go-librespot[3760]: time="2026-08-26T17:08:24+01:00" level=info msg="authenticated AP" username="ke*****et" Aug 26 17:08:24 volumio go-librespot[3760]: time="2026-08-26T17:08:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:08:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:08:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:08:24 volumio volumio[1337]: info: Connection to go-librespot Websocket closed Aug 26 17:08:26 volumio volumio[1337]: info: Getting Spotify volume Aug 26 17:08:26 volumio volumio[1337]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:08:26 volumio volumio[1337]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:08:26 volumio volumio[1337]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 26 17:08:26 volumio volumio[1337]: errno: -111, Aug 26 17:08:26 volumio volumio[1337]: code: 'ECONNREFUSED', Aug 26 17:08:26 volumio volumio[1337]: syscall: 'connect', Aug 26 17:08:26 volumio volumio[1337]: address: '127.0.0.1', Aug 26 17:08:26 volumio volumio[1337]: port: 9879, Aug 26 17:08:26 volumio volumio[1337]: response: undefined Aug 26 17:08:26 volumio volumio[1337]: } Aug 26 17:08:26 volumio volumio[1337]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:08:26 volumio sudo[3805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 17:07' Aug 26 17:08:26 volumio sudo[3805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"