-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-05-06 10:37:19 CEST. -- May 06 10:36:00 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:00 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670. May 06 10:36:02 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:02 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:02 volumio go-librespot[29464]: go-librespot daemon starting... May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="app state loaded" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=info msg="zeroconf server listening on port 33299" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="obtained new client token: AADc7+6AeEY4Zk4SUxo47BGWP/mAHZthEK9coYNn9ZaQCr1Wkg3fUh99YWJGNWd7UUkGc5eiAjmC8XuRpCyLFUjg0WXTYrHJRJZAsEL2hoxDeN8yKRJenYjqA2NEKQtWhnVZ3ler3H/VJZa1aLDTU/urq1OssY9ENooZK4u9DusHPYJTN4hyeNG2FnZI/aukMoDx/txjAGH/ETVdgpJ4t4bGqjIFvKWZMSgeJtziW9uL/lzg8sSPkOfzgg==" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="completed keyexchange" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=debug msg="completed challenge" May 06 10:36:02 volumio go-librespot[29464]: time="2025-05-06T10:36:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:03 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:03 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:03 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri May 06 10:36:04 volumio volumio[999]: info: Preload queue cleared May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:36lkJSDyMEZoWTqtRj8Q8q May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:1vU7pp4i0IR16qoGu5YNp2 May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:5OfCD9LJbl7jitYVThliKk May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:3do8YlihHKmbYT66Qt4cwH May 06 10:36:04 volumio volumio[999]: info: Preloading song: upnp/http://192.168.2.19:57223/cd/Control@0/My Music/Artists/Pink%20Floyd/Pink%20Floyd+The%20Dark%20Side%20of%20the%20Moon%20%28Deluxe%20Experience%20Version%29%20%5BRemastered%5D/b9903834a5f5409394847ad18467c72c May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:4hEk1CHls2JEDbKIKy8JxV May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:4gMgiXfqyzZLMhsksGmbQV May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:7K6xMPtAjTuLPNlJMLf5bS May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Take A Chance On Me.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - The Day Before You Came.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:3vCX8Eq8DdAsWyWTRQ7vYR May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:2kO7seuAYgbVWVvVzLZSP3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:6i4roOAXcuHWfmFuYFXZti May 06 10:36:04 volumio volumio[999]: info: Preloading song: spotify:track:3lyLqIn8mybyEFTs8JJaLf May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/SANDISK/100 70'S/1-01 All Right Now.m4a May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Bon Jovi - In These Arms.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Bon Jovi - Bed Of Roses.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Bon Jovi - Always (Album Version).mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Air Supply - All Out Of Love.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Happy New Year.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - People Need Love.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - My Love, My Life.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - My Love_ My Life.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Hasta Manana.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Slipping Through My Fingers.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Ring, Ring.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - So Long.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Eagle.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/André Hazes & Gerard Joling - Blijf Bij Mij.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Aerosmith - Walk This Way.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Black Eyed Peas - Where Is The Love.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Black Eyed Peas - Where Is The Love - Radio Edit.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Boudewijn de Groot - Avond (Album Versie).mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Bon Jovi - This Ain't A Love Song.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - One Of Us.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/BZN - My Number One.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Boney M. - Mary's Boy Child - Oh My Lord.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Boney M. - El Lute.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Boyzone - Words.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Alanis Morissette - Thank U (LP Version).mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Aerosmith - Dream On (Album Version).mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Alanis Morissette - Hand In My Pocket.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Barbra Streisand - Woman In Love.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Bruce Springsteen - Human Touch.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Fernando.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Chiquita.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - I Have A Dream.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Abba - Knowing Me, Knowing You.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Boudewijn de Groot - Avond.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Blof - Donker Hart.mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe (Stereo Layer).mp3 May 06 10:36:04 volumio volumio[999]: info: Preloading song: music-library/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe (Stereo Layer).mp3 May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:36lkJSDyMEZoWTqtRj8Q8q in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:36lkJSDyMEZoWTqtRj8Q8q May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:1vU7pp4i0IR16qoGu5YNp2 in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:1vU7pp4i0IR16qoGu5YNp2 May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:5OfCD9LJbl7jitYVThliKk in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:5OfCD9LJbl7jitYVThliKk May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:3do8YlihHKmbYT66Qt4cwH in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:3do8YlihHKmbYT66Qt4cwH May 06 10:36:04 volumio volumio[999]: info: Exploding uri upnp/http://192.168.2.19:57223/cd/Control@0/My Music/Artists/Pink%20Floyd/Pink%20Floyd+The%20Dark%20Side%20of%20the%20Moon%20%28Deluxe%20Experience%20Version%29%20%5BRemastered%5D/b9903834a5f5409394847ad18467c72c in service upnp_browser May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:4hEk1CHls2JEDbKIKy8JxV in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:4hEk1CHls2JEDbKIKy8JxV May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:4gMgiXfqyzZLMhsksGmbQV in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:4gMgiXfqyzZLMhsksGmbQV May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:7K6xMPtAjTuLPNlJMLf5bS in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:7K6xMPtAjTuLPNlJMLf5bS May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Take A Chance On Me.mp3 in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Take A Chance On Me.mp3' May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - The Day Before You Came.mp3 in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - The Day Before You Came.mp3' May 06 10:36:04 volumio volumio[999]: error: connect ECONNREFUSED 192.168.2.19:57223 {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"192.168.2.19","port":57223} May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:3vCX8Eq8DdAsWyWTRQ7vYR in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:3vCX8Eq8DdAsWyWTRQ7vYR May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:2kO7seuAYgbVWVvVzLZSP3 in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:2kO7seuAYgbVWVvVzLZSP3 May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:6i4roOAXcuHWfmFuYFXZti in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:6i4roOAXcuHWfmFuYFXZti May 06 10:36:04 volumio volumio[999]: info: Exploding uri spotify:track:3lyLqIn8mybyEFTs8JJaLf in service spop May 06 10:36:04 volumio volumio[999]: SPOTIFY: EXPLODING URI:spotify:track:3lyLqIn8mybyEFTs8JJaLf May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/SANDISK/100 70'S/1-01 All Right Now.m4a in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/SANDISK/100 70'S/1-01 All Right Now.m4a' May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Bon Jovi - In These Arms.mp3 in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Bon Jovi - In These Arms.mp3' May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Bon Jovi - Bed Of Roses.mp3 in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Bon Jovi - Bed Of Roses.mp3' May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Bon Jovi - Always (Album Version).mp3 in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Bon Jovi - Always (Album Version).mp3' May 06 10:36:04 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Air Supply - All Out Of Love.mp3 in service mpd May 06 10:36:04 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Air Supply - All Out Of Love.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Happy New Year.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Happy New Year.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - People Need Love.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - People Need Love.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - My Love, My Life.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - My Love, My Life.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - My Love_ My Life.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - My Love_ My Life.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Hasta Manana.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Hasta Manana.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Slipping Through My Fingers.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Slipping Through My Fingers.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Ring, Ring.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Ring, Ring.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - So Long.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - So Long.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Eagle.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Eagle.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/André Hazes & Gerard Joling - Blijf Bij Mij.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/André Hazes & Gerard Joling - Blijf Bij Mij.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Aerosmith - Walk This Way.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Aerosmith - Walk This Way.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Black Eyed Peas - Where Is The Love.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Black Eyed Peas - Where Is The Love.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Black Eyed Peas - Where Is The Love - Radio Edit.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Black Eyed Peas - Where Is The Love - Radio Edit.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Boudewijn de Groot - Avond (Album Versie).mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Boudewijn de Groot - Avond (Album Versie).mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Bon Jovi - This Ain't A Love Song.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Bon Jovi - This Ain't A Love Song.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - One Of Us.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - One Of Us.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/BZN - My Number One.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/BZN - My Number One.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Boney M. - Mary's Boy Child - Oh My Lord.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Boney M. - Mary's Boy Child - Oh My Lord.mp3' May 06 10:36:05 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Boney M. - El Lute.mp3 in service mpd May 06 10:36:05 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Boney M. - El Lute.mp3' May 06 10:36:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 671. May 06 10:36:05 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Boyzone - Words.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Boyzone - Words.mp3' May 06 10:36:06 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:06 volumio go-librespot[29497]: go-librespot daemon starting... May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Alanis Morissette - Thank U (LP Version).mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Alanis Morissette - Thank U (LP Version).mp3' May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="app state loaded" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Aerosmith - Dream On (Album Version).mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Aerosmith - Dream On (Album Version).mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Alanis Morissette - Hand In My Pocket.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Alanis Morissette - Hand In My Pocket.mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Barbra Streisand - Woman In Love.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Barbra Streisand - Woman In Love.mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Bruce Springsteen - Human Touch.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Bruce Springsteen - Human Touch.mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Fernando.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Fernando.mp3' May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=info msg="zeroconf server listening on port 35347" May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Chiquita.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Chiquita.mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - I Have A Dream.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - I Have A Dream.mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Abba - Knowing Me, Knowing You.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Abba - Knowing Me, Knowing You.mp3' May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="obtained new client token: AAB8ZJCPGxQT6Jq+dNzdsEEZH/K36StIxADJ20b8yFATF0brKAoNgWZDjSGW/KbOO5JAe7GG3xEGMvu5WuEYFCu57G/U3lJqAs2ldm76shVfnJ9qJmjxHvBcZ3Un+j0XbqITqqKUNPXOJ/Mt4TmNvyaGtky3eWsKeSPU5PVN9mW61gfhNBMD9W6/Xn69GKlmQ95FUB8HbEWi1X/aYww4xEnbUCBbQeXSHbswrQ8GJZHLMUhDNzAZV5APBg==" May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Boudewijn de Groot - Avond.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Boudewijn de Groot - Avond.mp3' May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="completed keyexchange" May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=debug msg="completed challenge" May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Blof - Donker Hart.mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Blof - Donker Hart.mp3' May 06 10:36:06 volumio go-librespot[29497]: time="2025-05-06T10:36:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:06 volumio volumio[999]: info: Exploding uri mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe (Stereo Layer).mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe (Stereo Layer).mp3' May 06 10:36:06 volumio volumio[999]: info: Exploding uri music-library/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe (Stereo Layer).mp3 in service mpd May 06 10:36:06 volumio volumio[999]: error: scanFolder - failure to stat '/mnt/USB/HEMA16GB/Andrea Bocelli And Marco Borsato - Because We Believe (Stereo Layer).mp3' May 06 10:36:06 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:09 volumio volumio[999]: info: New access token = BQBGOq-S4T1xupgZLcLJYjVLCAenqEu2ExTdSQT37HdkFbeE813UMSUzahyWMXfVnNmQhTCHODho_H1XoRpRFc04VsxelYhojU-Rias37JqjPkc6-ak3A5azVdS8rNyl5_H9tINeoXMFu31Q0dMHmVvzSLeIhUZbvw8IZTLaGjILtPIhNSfTUwzynsqn4E7PnW6wYvefZoFR7dPIK9V7-h4SyASWGCSRs6fCXzPaEq5KMNfJxngjCtwmUnZunr5V3L6l-Scys3A1fRE9X1kyARS6-BwNXZvS May 06 10:36:09 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:09 volumio volumio[999]: info: New access token = BQDXCt5AXr4_8SRUTxaJfeFB45MOo-W77qOPh7pCAqLhDhZpDepzHy3QaY6Ruw-m-Goka06rIrgtOn9wxgrEUqbGeM-y4Byw8R1rfqNn0lhKJuf2TW8IK9cDEfTfMimu7VAJQr1Yrn3R2DDPVWX0NLSViW5s8eOCM6pEZEeDzuQBX2CpUSpdLtKXq9RWitISkGJdNQLZAoFurFh8hHLny22EiNNQKoF2ZxUtCXDqe6wVUYZ8KifDIcHeXd4yYFA1Em5SOpQOLwfDke8K_4FqnXDdj1r9zguc May 06 10:36:09 volumio volumio[999]: info: New access token = BQBKMfAxYaSjJbUf6fEsbQOx8kn8JuE_pzy_gjpalPU9xtBjm6ZUrWZcY1jfy3RSbHN9LnovSqF7Ix4WgMevqzb9Z12R2u5P12Mz5f6dIxdK3KBZaCNtUL8KQg3f7KPV5RLSoJJcBVjV-CEbw622O2tS8qRgqHyHHhmkXdiezYScpGTOSsXiWQrNlSqijiGPI-bYatrk4gb6F7aXIfGBgDlpink3M7n60Q9-tJpJs2ynZyOjW3p1YuSQr4Nr6GTFtYevi_kIjM_ZyKlPdbGTHOJu20R74tZt May 06 10:36:09 volumio volumio[999]: info: New access token = BQDrsky2C1zMcU2JB6a5VxvlTQv3SfXwHyFDpUdXuCWIW16wPXvm7wzIXOzd9_Sll_rVlM_zjHBgDLrUR9_xXydXpPMEUgL_xhKk2sqiSeMvCXooBBfx4cXIKSt_VJ750QLtj_cX1ArcReQcMem4GDmHjUzu3qPoWdBBxrIFPYYY7J-xYhkfvGtrUEmmlPIPdlx60qFoodT0C8tcWK1jsIm73owjS8Flq7TMKkTKapYHgOIU4dI6umGp4ubqc8GRCBMFctxigE9tV8ZEAILdHgSY-J8iT6ao May 06 10:36:09 volumio volumio[999]: info: New access token = BQARRJKuyOf52EmyVUDdD8qHIhqtzJFFZLFw5KhDewuiQtCzoqCnFqHlH1PuLlmSUsPpRH6apY0aZuSwXLo1J5bMTgf2qvdghjmePjPKD_lwew2WOnjxe79Nq-vJ1BI0Xc7poExgaD2cicuG3Aa5-nLFfgyKGZ-HGWqR-KB9u3n8Azr5oy_ud60UOMhpxUIFsNA0cAKKpG_4Ysk6nGUiuhaefO1mZwNPxXR-wVUy9KDxhI_Age-WVTGdW6Y9EnR9kB-nV4oQMiRPnLxMmpFCtH1LEqgO7pYy May 06 10:36:09 volumio volumio[999]: info: New access token = BQASRQs4ElEfUWxgslcIlPs0LivBVqBbzzVJy3H2FEecfLAJJwB9F8XP2vTPKaEXjYPPGPKSqXFWR_j-BS-zWVNJhZScJA4JZFtF0JE1_ntf6F1paEoZ-oxXsmCQ01TnoK5VR88VCsR11mdetNcDy1A2cCs6_gYeULdGLM96eeW0_cvtW3deu7goCWY4oWUeTC5cYIlaAeQFIEl7GS8r2SUfMsSphvkLoJ60ZGlZ1SW74D1sLHiBgXubdvi36xPRMXMmj-3zUXPcLc5RbrlGiMqwS7kTDAQs May 06 10:36:09 volumio volumio[999]: info: New access token = BQDk9L1oyvF1D4i8jfr1Snv-JLJTb-3aYldONrB3XzDPavKBJe1Y60pPpmqr8ukpDv68ahPDO6QNBjr8fcWvDWuoP5axrQQK9Mnlx36kIiiXrsH8GhfFwRc714yyNiEkRAz7uZ32fDOiR-Io106qyiZNFdVnMJAwEbaGvAxACFUAGyzfjQeAOwSZyiPH8AB9__OD_NOr0VZTVpaUNuNiv6HydlKPBiZI8Qrocg3SDLcJGsYMdVbxQ69HuhcWiailFSqON2COXlaRFYLfoPjGHZ5XfDI0oMRx May 06 10:36:09 volumio volumio[999]: info: New access token = BQD_70-85CvJdR-qX7Wv8s06f46VR82VP5tTfeOKwoyeLgLr3bBVOKjn9NApjOPAVYB2q-iPw8D-gPSjs33del7wYn4Lf0Q65DgzNBVIXnL-Q1bEJlk6Ksqx3Wp_Ve3GECUTCdJ4-YfhjVD7ADg4pvXv2nm_JgOdHTvmyfG6-7kgYvQZLPyxb06UuJW79uKiFKMv6lDuMgB5kBCxmB8Qd7b0b8_T0r4DnMR0fSbJy8e0kqWptq0mDTeZE_sbM4NDF0xkJ4wIefdTjtfk2s0YX4bliEBqxDeb May 06 10:36:09 volumio volumio[999]: info: New access token = BQDS8wjN5ETTGsf_vzaQ7WAZUcsNOu7xLVrMu6qafxrJTtmXBqvCc6ySU6goxbkD_PFikvknlG3lnzIyY0cEkARZn-pNuY155TNQ8P_EQV_K5P8WYIetOLf09QVBe5R6yvjKCjjEhdB8GiQ_FscVvYDMlYGdQp_pVQhYt-Dzmd3nKQfxkXDWeuJkmT_3XlDlwkvhpvfM78u7WyxZcW41TdJdEcNMcPgNjnEpUdLPK4ud7xmCopDmKUxuVcsdDYbibHZnQbvO-mOfIe2olTZ1S9ODwv4MRv2z May 06 10:36:09 volumio volumio[999]: info: New access token = BQDgXPTgY4FlTzJ6GxlGVWFRHHVeHv1QxskbyTRk5X-pTsqKqye-qgJGe3KxLagmlDheqwf2tfPugX8gNKTnRyLJnm8TB-NPPwQM2C0g0lKZIEOGS7a3iPo8l_ZBlJWYCYlwH8gKkxfn-JI-qR3xXwdJd_KiVPuQahKsNoZKB0p7unyZJa9cpGYvVzh3qRt3BSO-2VlNoB0wbi_gkpJ6CfpfS6IrXfuQxZXFvQgF-9IVWIfAL9A40hOwHuwtU0qlar9s4WYV7JMDIChTG178-Oda1YDzf4LI May 06 10:36:09 volumio volumio[999]: info: New access token = BQD1LIviBCnTF60lzSqwbbzA-FHzg5S7T8AaXNx_7W4hl7-D23BcQmwGXXAkMweKuNsQO9GmuSvE8tzjxgZ2ovRJLQMyJJfBTYZ8YAxge_16TqPoeFsm_NEkS23rDkxsSLsJkmnoCReiTuzmm41D810iDB0rYCZ0Ibd8MiF7TZl5RNW1NHv6HgB92MT9ysn2y-2uSiveq1n2znAjDwIWRs39vhiguIljPMk05MQInf2AeB29MBBC-f5gmOA8i1wWYQptWB3wlMqQAMKY1oxkXEWKwQ8SwaSU May 06 10:36:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 672. May 06 10:36:09 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:09 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:09 volumio go-librespot[29515]: go-librespot daemon starting... May 06 10:36:09 volumio go-librespot[29515]: time="2025-05-06T10:36:09+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:09 volumio go-librespot[29515]: time="2025-05-06T10:36:09+02:00" level=debug msg="app state loaded" May 06 10:36:09 volumio go-librespot[29515]: time="2025-05-06T10:36:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:09 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1vU7pp4i0IR16qoGu5YNp2","service":"spop","name":"How My Heart Was Won","artist":"DI-RECT","album":"Sphinx","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b27382133335ee0df0d70d97d0f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:09 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:36lkJSDyMEZoWTqtRj8Q8q","service":"spop","name":"You're the Inspiration - 2006 Remaster","artist":"Chicago","album":"Chicago 17 (Expanded & Remastered)","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b273192f3a588427f2cbf8365506","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:09 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5OfCD9LJbl7jitYVThliKk","service":"spop","name":"Ode To Freedom","artist":"ABBA","album":"Voyage","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273225d9c1b06ca69aec9b08381","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:09 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4hEk1CHls2JEDbKIKy8JxV","service":"spop","name":"Breakfast In America - Remastered","artist":"Supertramp","album":"Breakfast In America (Deluxe Edition)","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b273ceed62376a6b5405db7e3ca0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4gMgiXfqyzZLMhsksGmbQV","service":"spop","name":"Another Brick in the Wall, Pt. 2","artist":"Pink Floyd","album":"The Wall","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b2735d48e2f56d691f9a4e4b0bdf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3vCX8Eq8DdAsWyWTRQ7vYR","service":"spop","name":"Stark - Long Version","artist":"Ich + Ich","album":"Stark","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b2732ae39a8b1abea88fe4519acd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3do8YlihHKmbYT66Qt4cwH","service":"spop","name":"De Polder","artist":"Erwin De Vries","album":"Haim","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c2cb757f9f0aec08268dbbd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6i4roOAXcuHWfmFuYFXZti","service":"spop","name":"Have A Little Faith","artist":"Son Mieux","album":"Have A Little Faith","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273fdca9b64c16c7eb735eb3447","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7K6xMPtAjTuLPNlJMLf5bS","service":"spop","name":"Another Brick in the Wall, Pt. 1","artist":"Pink Floyd","album":"The Wall","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b2735d48e2f56d691f9a4e4b0bdf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2kO7seuAYgbVWVvVzLZSP3","service":"spop","name":"1 Dans","artist":"Guusje","album":"Doodgewoon","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273933c5b016b863372a1d5f7fb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio volumio[999]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3lyLqIn8mybyEFTs8JJaLf","service":"spop","name":"Home","artist":"Michael Bublé","album":"It's Time","type":"song","duration":225,"albumart":"https://i.scdn.co/image/ab67616d0000b273c6ad2b2b62b581a23a7c1759","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=info msg="zeroconf server listening on port 46163" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="obtained new client token: AAABmGmUgH+k2GdFJfUVE7LfGrRbCVg2fQjOQoN3NRtKwMW3a1YYiZBapMMtIV3w7KJFb33NSrbj5oDyK/0CwhKxTYGIdjU5ijexUeGJsBYav0ElDliXmhX4vynqkOLAFO2yrFCiUJp9HsSDBUMqHQMMoHhXeBee0S32YIb1Lz7Lv/ZEdONe3pQuHQKf4iUMw84g0OfxrurMbSDbYX7WC6BinAN51h/q8P0gSEX1THqcWH45YaM+E1qt1w==" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="completed keyexchange" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=debug msg="completed challenge" May 06 10:36:10 volumio go-librespot[29515]: time="2025-05-06T10:36:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:12 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:12 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 673. May 06 10:36:13 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:13 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:13 volumio go-librespot[29529]: go-librespot daemon starting... May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=debug msg="app state loaded" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=info msg="zeroconf server listening on port 34215" May 06 10:36:13 volumio go-librespot[29529]: time="2025-05-06T10:36:13+02:00" level=debug msg="obtained new client token: AABzQnSBN8PylYbgwT2X6Z1hBQ6FM7CMJ64U6s4Vy9BmnmJY0A9mXWxI2hQlOc1NApeaaRUEoUSY30FO+RUc2hr90e42g0QJMErXp9573sNEBDkE+PDC4Fl0l3v5ugJSO2ToyhB4gOax+uZBVstNKCiDOjVFEL9HEJbdDfG2JoBr9NC68i9GGaQ823nlizZATMcfCl7nFSZ979RCD3V6ERRWBXrUQPPaVYA7Z6dOGqsnJxtbB7//MCzG2A==" May 06 10:36:14 volumio go-librespot[29529]: time="2025-05-06T10:36:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:14 volumio go-librespot[29529]: time="2025-05-06T10:36:14+02:00" level=debug msg="completed keyexchange" May 06 10:36:14 volumio go-librespot[29529]: time="2025-05-06T10:36:14+02:00" level=debug msg="completed challenge" May 06 10:36:14 volumio go-librespot[29529]: time="2025-05-06T10:36:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:15 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:15 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 674. May 06 10:36:17 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:17 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:17 volumio go-librespot[29563]: go-librespot daemon starting... May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="app state loaded" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=info msg="zeroconf server listening on port 43735" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="obtained new client token: AABJcoBAG5Jh6/zRYv/D8Nf7QrXf+dzJY7dXvoeb3qpOHdTVolQdnA3AK9pgsnVY1Jgu1O9EXCoj55oisnEV5ryHaOkgppmv6VASbsALgSxrTa0oG424CxI/9vQGgWGKiQ1g4YO/ODUmh3RiuBTFZlTKQjXM5DdST30AZvdPL+w/3d0FWaDjsgeMgUft0egUHbI7ZnTX60ou1twAcIW5ETjh4xDbluGRECnBTC/9YS7JmiYzNCBxFm1fKQ==" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="completed keyexchange" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=debug msg="completed challenge" May 06 10:36:17 volumio go-librespot[29563]: time="2025-05-06T10:36:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:18 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:18 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 675. May 06 10:36:20 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:21 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:21 volumio go-librespot[29579]: go-librespot daemon starting... May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="app state loaded" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=info msg="zeroconf server listening on port 33141" May 06 10:36:21 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="new websocket client" May 06 10:36:21 volumio volumio[999]: info: Connection to go-librespot Websocket established May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="obtained new client token: AAD4wUH7gLEpcDJsukK2f1n5fej7S7nvE5FVnqWjrLJKFrI18tElsoOOA1A3VgrMWr+4oubcq/yiwa82ZVJiOFJVkAe+4hoZvUB8yJNSz5YrA1KYUT+zHO42EgGJj392VZD/WR8brFJDxLotBoq0E8JCjhrvBZYytGCBzFpOgAEfzuiMZNe97BLx7swbb9MDdtL7qCpZrablPWwAAOXwm3qOK25dUgLz5I1yLvew8mT4dvYOiJxz4YQkuQ==" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="completed keyexchange" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=debug msg="completed challenge" May 06 10:36:21 volumio go-librespot[29579]: time="2025-05-06T10:36:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:21 volumio volumio[999]: info: Connection to go-librespot Websocket closed May 06 10:36:24 volumio volumio[999]: info: Getting Spotify volume May 06 10:36:24 volumio volumio[999]: (node:999) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:24 volumio volumio[999]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) May 06 10:36:24 volumio volumio[999]: (node:999) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 45) May 06 10:36:24 volumio volumio[999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 06 10:36:24 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 06 10:36:24 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:24 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 676. May 06 10:36:24 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:24 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:24 volumio go-librespot[29611]: go-librespot daemon starting... May 06 10:36:24 volumio go-librespot[29611]: time="2025-05-06T10:36:24+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:24 volumio go-librespot[29611]: time="2025-05-06T10:36:24+02:00" level=debug msg="app state loaded" May 06 10:36:24 volumio go-librespot[29611]: time="2025-05-06T10:36:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=info msg="zeroconf server listening on port 39329" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="obtained new client token: AACsGlIYRm+tdXawAjqa6fyt0GsJlvemgf+hVq/TiDgSHTiY3Py3hY5hZRPGw9CvZgYLFcKkfK29tRv31i9kKvtj/wsJIKWDarVI87yUhA7xWRbQxAkEwVxVnbFBxdokTY1AP1+uqJoRoUdLnrblNTlwS2dALYaeIkhRCiSD+CiyN81SKE26qIK5UjxMsIZy/XqV8UksuoFCWwmCT6mdQ4tuY4Wf64f6r3+5C5lsDqjHWIWL6a8l7Gs=" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="completed keyexchange" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=debug msg="completed challenge" May 06 10:36:25 volumio go-librespot[29611]: time="2025-05-06T10:36:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:27 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:27 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 677. May 06 10:36:28 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:28 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:28 volumio go-librespot[29627]: go-librespot daemon starting... May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=debug msg="app state loaded" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=info msg="zeroconf server listening on port 43939" May 06 10:36:28 volumio go-librespot[29627]: time="2025-05-06T10:36:28+02:00" level=debug msg="obtained new client token: AABTG4m7egAOxnMQhVwvCv4dMBJPuOPiLo3PhSZiEAauEcuQT9tMQf7aWVyaXipo3d4o3R9TbHF8eVZSShS6ChHU1mdHp+ETMqmqN/CAglYn0bNlUGLdWrtCi8qtOR0WgwFzhyATTjw9xA0+I1tr1IFFpFOVAREN9QqyHUVcyBFrKuEUef1qBdnv0xzbOhV0F76O1wACaMG0n06sWpp5RRMIJ814H9CEALEbVDHcx74SNLzLZ0IDT6rDtw==" May 06 10:36:29 volumio go-librespot[29627]: time="2025-05-06T10:36:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:29 volumio go-librespot[29627]: time="2025-05-06T10:36:29+02:00" level=debug msg="completed keyexchange" May 06 10:36:29 volumio go-librespot[29627]: time="2025-05-06T10:36:29+02:00" level=debug msg="completed challenge" May 06 10:36:29 volumio go-librespot[29627]: time="2025-05-06T10:36:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:29 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 06 10:36:29 volumio volumio[999]: info: Preload queue cleared May 06 10:36:30 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:30 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 678. May 06 10:36:32 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:32 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:32 volumio go-librespot[29643]: go-librespot daemon starting... May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="app state loaded" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=info msg="zeroconf server listening on port 34911" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="obtained new client token: AABZ2EWSoK3TNd3mhnXu5xJ0WYkAQprjYo3Drm+tJ0H8og4HNxyoWqYnvGhBr9YbnUQzY50vU0RASDvmYQDHjzCR4B6L3CSyK1Vv10RmFlkxwJmMrlSwQePWP7VFilvaqBHDQqNhB50JZVrg51Dy+b4J8OKpiogdtB8upP9houS2evFPs3GDZSz08jK+vv3YbqtbH5BH5LSJgndxZywHM8crZAzEeGqjc4MoXtKt/UntUhmnMlBUFkD3MQ==" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="completed keyexchange" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=debug msg="completed challenge" May 06 10:36:32 volumio go-librespot[29643]: time="2025-05-06T10:36:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:33 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:33 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 679. May 06 10:36:35 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:36 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:36 volumio go-librespot[29715]: go-librespot daemon starting... May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="app state loaded" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=info msg="zeroconf server listening on port 39863" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="obtained new client token: AAB7xi7DnGfXnKRABEP8oqMguRZuNS7xeLEd82ia1ZhK3LHQHl6U66kGPfB7HjfADh29ntLOOQmmu01tjgRTYqbQUd7Ptf3mTOg04hr3vTjKiXMspBNZPeDg4idsRrnKktfM29c54E8vThGB5bG69S8xmnJj3DvPNwpNHCAZQfHZGKvqg4WdBCeWZNToe5xFl6k0SkJlWqZNvbC2C1bhYnjh3toxZZcWX8RMGSUK9QFN5EV6c3Q2RjBZyA==" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="completed keyexchange" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=debug msg="completed challenge" May 06 10:36:36 volumio go-librespot[29715]: time="2025-05-06T10:36:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:36 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:36 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:39 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:39 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 680. May 06 10:36:39 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:39 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:39 volumio go-librespot[29732]: go-librespot daemon starting... May 06 10:36:39 volumio go-librespot[29732]: time="2025-05-06T10:36:39+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:39 volumio go-librespot[29732]: time="2025-05-06T10:36:39+02:00" level=debug msg="app state loaded" May 06 10:36:39 volumio go-librespot[29732]: time="2025-05-06T10:36:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=info msg="zeroconf server listening on port 46749" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="obtained new client token: AAD7i5K9LHPQ1nSKsYOrrntrC70z369sMgf1HJpZ6Ah/6L963vKO5qk5zOk2BlcSJme4lmJXw9HSInBukzcAfLsvec1mZbkWQNm66kOI0U7XVqs0eUJfd3I8+YXqb/wYW3uYl9M5oQBQmoxCexgYZPmQcgEMDLvgDNJ03Ev2vTReKlziGRrH0RROG4CtXy4782DhF9jwsPKpgAd+1lOxt87XSYs839vyQTmG6rAjXGZhX06q0fLLvds=" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="completed keyexchange" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=debug msg="completed challenge" May 06 10:36:40 volumio go-librespot[29732]: time="2025-05-06T10:36:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:41 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 06 10:36:41 volumio volumio[999]: info: In handleBrowseUri, curUri=spotify May 06 10:36:41 volumio volumio[999]: info: Preload queue cleared May 06 10:36:42 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:42 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 681. May 06 10:36:43 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:43 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:43 volumio go-librespot[29748]: go-librespot daemon starting... May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=debug msg="app state loaded" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=info msg="zeroconf server listening on port 38077" May 06 10:36:43 volumio go-librespot[29748]: time="2025-05-06T10:36:43+02:00" level=debug msg="obtained new client token: AABNdxqaV91u+s7V6SW7p9FhTPj2W7k7POCFJdqCWodC06VTHKvyZNQTpsybgkNAdymVgJwzt5TL7VlrJgQtZatYM8ThqyI1s+UHZpRIV+EZ7y6UbRv7tclTLSoHNMj3SGFFxBnYDt8d578vvZhs3IoXR3MCXoMqrcgi2q2kW/9gZkWCJP6iRXliV6pgJQ/HCi/0ml5vI0iSYQQSqW4oZEticK9o1aBbOHCjlEWOkjpIOs1DwzjqenCAWw==" May 06 10:36:44 volumio go-librespot[29748]: time="2025-05-06T10:36:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:44 volumio go-librespot[29748]: time="2025-05-06T10:36:44+02:00" level=debug msg="completed keyexchange" May 06 10:36:44 volumio go-librespot[29748]: time="2025-05-06T10:36:44+02:00" level=debug msg="completed challenge" May 06 10:36:44 volumio go-librespot[29748]: time="2025-05-06T10:36:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:45 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:45 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 682. May 06 10:36:47 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:47 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:47 volumio go-librespot[29781]: go-librespot daemon starting... May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=debug msg="app state loaded" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=info msg="zeroconf server listening on port 36479" May 06 10:36:47 volumio go-librespot[29781]: time="2025-05-06T10:36:47+02:00" level=debug msg="obtained new client token: AAA8F35oprN02Pvc9Smtk1m8Xj/453e4y6eqEMkhEB2imIPwBSasyo0uAoqyLAKrPhoXsFPhdXEftCGGCSLqf4A7uUkvzXi0jV0B3e8xe75Zu+tEeZPxcT0s4tR2eVsPa5MFEm+MsmPFKlmqt22kwbiUWMNhq1aZzCd2f/Vfzar74BgxfNBj9RRy07c4kz4DelEik+faiSxM2ilfUGVftP7c8qXr7CxqlsIY6iVLzqyyRzpGUlSFwfzGyg==" May 06 10:36:48 volumio go-librespot[29781]: time="2025-05-06T10:36:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:48 volumio go-librespot[29781]: time="2025-05-06T10:36:48+02:00" level=debug msg="completed keyexchange" May 06 10:36:48 volumio go-librespot[29781]: time="2025-05-06T10:36:48+02:00" level=debug msg="completed challenge" May 06 10:36:48 volumio go-librespot[29781]: time="2025-05-06T10:36:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:48 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:48 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 683. May 06 10:36:51 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:51 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:51 volumio go-librespot[29796]: go-librespot daemon starting... May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="app state loaded" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=info msg="zeroconf server listening on port 44441" May 06 10:36:51 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="new websocket client" May 06 10:36:51 volumio volumio[999]: info: Connection to go-librespot Websocket established May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="obtained new client token: AAA2Sh50jCZQ8M1ObYHnzVEGkrN0T46JSKU5whWz47JcQjxFX6MozFALEQcqGHL9xvfEA0wIiWh9MHyidEi5LEv17KRKbha3DNfz8+VcJOt7JB38BYX3hAPXl3oC5t/5QY1NcN/8TqY6Rt6cRUai02x0QymsX3ENYMU8UaTQBi+OEHwCRDQuI3VE3M4FZuLFv0UjwaSRPxbSyBPDWJB3Y7BeLPVqPA2olnKGuxL7xGImJ9RF+aULadhxbg==" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="completed keyexchange" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=debug msg="completed challenge" May 06 10:36:51 volumio go-librespot[29796]: time="2025-05-06T10:36:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:51 volumio volumio[999]: info: Connection to go-librespot Websocket closed May 06 10:36:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:53 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 10:36:53 volumio volumio[999]: info: Preload queue cleared May 06 10:36:54 volumio volumio[999]: info: Getting Spotify volume May 06 10:36:54 volumio volumio[999]: (node:999) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:54 volumio volumio[999]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) May 06 10:36:54 volumio volumio[999]: (node:999) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 46) May 06 10:36:54 volumio volumio[999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 06 10:36:54 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 06 10:36:54 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:54 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 684. May 06 10:36:54 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:55 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:55 volumio go-librespot[29829]: go-librespot daemon starting... May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="app state loaded" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=info msg="zeroconf server listening on port 44225" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="obtained new client token: AACP2iK8sxXlJCIZyW1bXzW7GLQ5jUC6e5dmEdUI9Ftld0IH52RkJEOxqe82r0uX5TrqEu9ljxaAsOg2LTOu4YvN/XD1CQ6R1bwDemuVUVoVCZK1dOMNN0tjVIoPh++imrsVrh/qPw+N9cR5ZZ4+IHPzBAQnOGjVEdWIndoHYfrjmtPK21ZB3CKanyk9GS/M1YSCTZoOtYeVz62PnzXOtzzvbqwWjeSBHQb5ZDk3YSd6EBYatnePT2YWtA==" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="completed keyexchange" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=debug msg="completed challenge" May 06 10:36:55 volumio go-librespot[29829]: time="2025-05-06T10:36:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:36:57 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:36:57 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:36:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:36:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 685. May 06 10:36:58 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:36:58 volumio systemd[1]: Started go-librespot Daemon. May 06 10:36:58 volumio go-librespot[29846]: go-librespot daemon starting... May 06 10:36:58 volumio go-librespot[29846]: time="2025-05-06T10:36:58+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:36:58 volumio go-librespot[29846]: time="2025-05-06T10:36:58+02:00" level=debug msg="app state loaded" May 06 10:36:58 volumio go-librespot[29846]: time="2025-05-06T10:36:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=info msg="zeroconf server listening on port 38263" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="obtained new client token: AABtV5vOsZd4YLsAdaWaqU5LJJ9ofh0jJWPy5ygJaJsu+9njT39IwqNihZ0zXMrLVB71JOf5yO+EVrOE2wUR0ZTJGpvBYX3bj5Jl29LxK4zWu6rsRjqs/6vvnAVha9xHxNdJWh99s3dVtSmrkCN3mecXMMSzLIp82n8lySXjfgDvjtbyld8DxN+BY7ROSjFha67m739GVAFi7LAEXiAoSzU8fx+/na4CjbSuQ4NpjyLW6HQsRijIOII=" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="completed keyexchange" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=debug msg="completed challenge" May 06 10:36:59 volumio go-librespot[29846]: time="2025-05-06T10:36:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:36:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:36:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:37:00 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:00 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:37:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686. May 06 10:37:02 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:37:02 volumio systemd[1]: Started go-librespot Daemon. May 06 10:37:02 volumio go-librespot[29860]: go-librespot daemon starting... May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=debug msg="app state loaded" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=info msg="zeroconf server listening on port 39109" May 06 10:37:02 volumio go-librespot[29860]: time="2025-05-06T10:37:02+02:00" level=debug msg="obtained new client token: AAD7zS1wO9Hh4ERcWQKTnhVWk3Xj+ssTK63GHZf83FckPVo4D3PXhh5ZTf2L4SwABPi2wK/VeGG09uV05pPTe+jAXOf1f4IHKjLt2zN4u/E/6z4y4elfxQYM4+yrDSmMJuIm7AnWl5j8K2hDHalUjVDJ5tFPegW+z9YrFTkAUeei7rUoXXRLu1hHmjdHmwSdQSbHyN3VViksPxZFZqcZzVFbwMCNhnkQ+ID7WVeEkPJKkaax6Uab/OKaRQ==" May 06 10:37:03 volumio go-librespot[29860]: time="2025-05-06T10:37:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:37:03 volumio go-librespot[29860]: time="2025-05-06T10:37:03+02:00" level=debug msg="completed keyexchange" May 06 10:37:03 volumio go-librespot[29860]: time="2025-05-06T10:37:03+02:00" level=debug msg="completed challenge" May 06 10:37:03 volumio go-librespot[29860]: time="2025-05-06T10:37:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:37:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:37:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:37:03 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:03 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:05 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 10:37:05 volumio volumio[999]: info: Preload queue cleared May 06 10:37:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:37:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 687. May 06 10:37:06 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:37:06 volumio systemd[1]: Started go-librespot Daemon. May 06 10:37:06 volumio go-librespot[29894]: go-librespot daemon starting... May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="app state loaded" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=info msg="zeroconf server listening on port 43985" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="obtained new client token: AAC2SolaBdQ4n3Eths3hIruNS+0Y46L0sMVIfqIpxr2CDZs7hATJlHVGRyhO6cUZvSzN7fv+dY4XcG5eBDqiDIgtuP6V8jgYL5CkQ+dwxxekDT9kSy7cQBc48Mya1Vs62Tko18KqJWcpvFFMU2Rd+eqvkwy2zYBqxsH3il76beWtvzk8hPZtlAA0thKymp7H9pOowsh20cOK45Ht8Vq6Rjt29N+E769T5mJEBw18GhQxv+VSCLRblqd0hw==" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="completed keyexchange" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=debug msg="completed challenge" May 06 10:37:06 volumio go-librespot[29894]: time="2025-05-06T10:37:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:37:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:37:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:37:06 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:06 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:09 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:09 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:37:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 688. May 06 10:37:09 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:37:10 volumio systemd[1]: Started go-librespot Daemon. May 06 10:37:10 volumio go-librespot[29910]: go-librespot daemon starting... May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="app state loaded" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=info msg="zeroconf server listening on port 43877" May 06 10:37:10 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="obtained new client token: AABb5zVJ1ewmQUIfehSLcFJjU+OhLMpMpi4JO3Z8LS92DewGmEtg09B3/i6VaNWqqdiX26O9mSSKSDvvCJm0ME3HdpoepTmV66whuWn/treibn3CuyqqWeT34rl5kRm3TSJeK/GTU097epSXDhr66X/CUabJU5cg/q+cTzognRtRlq/nYqfgXfMmv3rMovv4g28kf/NarQDdyGSACN7+syvx50gzPe9aPvQhfPt2ec+Uuhn9Flrsf0n8Dw==" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="completed keyexchange" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=debug msg="completed challenge" May 06 10:37:10 volumio go-librespot[29910]: time="2025-05-06T10:37:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:37:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:37:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:37:10 volumio volumio[999]: info: Preload queue cleared May 06 10:37:11 volumio volumio[999]: info: May 06 10:37:11 volumio volumio[999]: ---------------------------- MPD announces system playlist update May 06 10:37:11 volumio volumio[999]: info: Ignoring MPD Status Update May 06 10:37:11 volumio volumio[999]: info: May 06 10:37:11 volumio volumio[999]: ---------------------------- MPD announces state update: player May 06 10:37:11 volumio volumio[999]: info: ControllerMpd::getState May 06 10:37:11 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status May 06 10:37:11 volumio volumio[999]: info: ------------------------------ 4ms May 06 10:37:11 volumio volumio[999]: info: sendMpdCommand status took 7 milliseconds May 06 10:37:11 volumio volumio[999]: verbose: ControllerMpd::parseState May 06 10:37:11 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 10:37:11 volumio volumio[999]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 10:37:11 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo May 06 10:37:11 volumio volumio[999]: info: ControllerMpd::pushState May 06 10:37:11 volumio volumio[999]: info: CoreCommandRouter::servicePushState May 06 10:37:11 volumio volumio[999]: info: CorePlayQueue::getTrack 0 May 06 10:37:11 volumio volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4899226,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Elton John - Sacrifice","artist":"ndr_ndr1_han_mp3","album":null,"uri":"http://icecast.ndr.de/ndr/ndr1niedersachsen/hannover/mp3/128/stream.mp3","trackType":"mp3"} May 06 10:37:11 volumio volumio[999]: verbose: CURRENT POSITION 0 May 06 10:37:11 volumio volumio[999]: info: CoreStateMachine::syncState stateService play May 06 10:37:11 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus play May 06 10:37:11 volumio volumio[999]: info: Received an update from plugin. extracting info from payload May 06 10:37:11 volumio volumio[999]: info: CoreStateMachine::pushState May 06 10:37:11 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 10:37:11 volumio volumio[999]: info: CoreCommandRouter::volumioPushState May 06 10:37:11 volumio volumio[999]: info: CoreStateMachine::pushState May 06 10:37:11 volumio volumio[999]: info: CoreCommandRouter::volumioPushState May 06 10:37:11 volumio volumio[999]: info: ------------------------------ 69ms May 06 10:37:12 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:12 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:37:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 689. May 06 10:37:13 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:37:13 volumio systemd[1]: Started go-librespot Daemon. May 06 10:37:13 volumio go-librespot[29932]: go-librespot daemon starting... May 06 10:37:13 volumio go-librespot[29932]: time="2025-05-06T10:37:13+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:37:13 volumio go-librespot[29932]: time="2025-05-06T10:37:13+02:00" level=debug msg="app state loaded" May 06 10:37:13 volumio go-librespot[29932]: time="2025-05-06T10:37:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=info msg="zeroconf server listening on port 33645" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="obtained new client token: AADJNHEaaCktZ47/FSP5GKBjCWyY1yXmqcQolC0S+gc3ibkxnK7fD9WAAOMCLEC2xKojj5t7M7EhRH2LU7t4b+QfZwUG9bclZEylP3FuEDo11UPBBU+VsKvD8zEcsGqhM/Yfksb7IS1MwlDUlG3cRykEVCRZtXDok4b4aP8OPXnVj/XZAdWxWiO+5Z3AEKU9PTO5n151nHTXPdm+GT9iit7FdQo3J4oeUAmHio6CL8mWl7x8jXEOn9w=" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="completed keyexchange" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=debug msg="completed challenge" May 06 10:37:14 volumio go-librespot[29932]: time="2025-05-06T10:37:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:37:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:37:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:37:15 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:15 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 06 10:37:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 690. May 06 10:37:17 volumio systemd[1]: Stopped go-librespot Daemon. May 06 10:37:17 volumio systemd[1]: Started go-librespot Daemon. May 06 10:37:17 volumio go-librespot[29967]: go-librespot daemon starting... May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=info msg="running go-librespot 0.2.0" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=debug msg="app state loaded" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=info msg="zeroconf server listening on port 39673" May 06 10:37:17 volumio go-librespot[29967]: time="2025-05-06T10:37:17+02:00" level=debug msg="obtained new client token: AAAq/u6/9gqjBEJqASXo0zvgm3Rp+zi/KDu3zm7FR+konuCb25w9qIAQsa7d0uOiQnz6BJo0fFyorg0SXqNwh1/rkLhFzTtJ5pHaxSEzOzPF7raF9/jA6U/EhxFeYJnPGpd8qOtzwc0xj0HzbeB/qJamogn4/cajD+4ni05oH3vKIwpBpvKEssTQP4MK6Xxvy12TOvxzDB/Gn3WKrKn3TAgxG5uMAAfYTx7zjvaOkCSXwUOSP9QUhiHK3g==" May 06 10:37:18 volumio go-librespot[29967]: time="2025-05-06T10:37:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 06 10:37:18 volumio go-librespot[29967]: time="2025-05-06T10:37:18+02:00" level=debug msg="completed keyexchange" May 06 10:37:18 volumio go-librespot[29967]: time="2025-05-06T10:37:18+02:00" level=debug msg="completed challenge" May 06 10:37:18 volumio go-librespot[29967]: time="2025-05-06T10:37:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " May 06 10:37:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 06 10:37:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 06 10:37:18 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 10:37:18 volumio volumio[999]: info: Initializing connection to go-librespot Websocket May 06 10:37:18 volumio volumio[999]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 06 10:37:18 volumio volumio[999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 10:37:18 volumio volumio[999]: TypeError: Cannot read property 'length' of undefined May 06 10:37:18 volumio volumio[999]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 06 10:37:18 volumio volumio[999]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 06 10:37:18 volumio volumio[999]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 06 10:37:18 volumio volumio[999]: at Parser.emit (events.js:315:20) May 06 10:37:18 volumio volumio[999]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 06 10:37:18 volumio volumio[999]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 06 10:37:18 volumio volumio[999]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 06 10:37:18 volumio volumio[999]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 06 10:37:18 volumio volumio[999]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 06 10:37:18 volumio volumio[999]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 06 10:37:18 volumio volumio[999]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 06 10:37:18 volumio volumio[999]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 06 10:37:18 volumio volumio[999]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 06 10:37:18 volumio volumio[999]: at IncomingMessage.emit (events.js:327:22) May 06 10:37:18 volumio volumio[999]: at endReadableNT (internal/streams/readable.js:1327:12) May 06 10:37:18 volumio volumio[999]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 06 10:37:18 volumio volumio[999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 10:37:19 volumio sudo[29988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 10:36 May 06 10:37:19 volumio sudo[29988]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"