-- Logs begin at Wed 2025-04-30 02:21:26 UTC, end at Wed 2025-04-30 05:01:40 UTC. -- Apr 30 05:00:00 volumio volumio-remote-updater[664]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m" Apr 30 05:00:00 volumio volumio-remote-updater[664]: PROGRESS: 30, STATUS: "Creating backup", ETA: "5m" Apr 30 05:00:00 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:00:00 volumio volumio[1281]: updateProgress Apr 30 05:00:00 volumio volumio[1281]: { Apr 30 05:00:00 volumio volumio[1281]: downloadSpeed: '', Apr 30 05:00:00 volumio volumio[1281]: eta: '5m', Apr 30 05:00:00 volumio volumio[1281]: progress: 30, Apr 30 05:00:00 volumio volumio[1281]: status: 'Creating backup' Apr 30 05:00:00 volumio volumio[1281]: } Apr 30 05:00:00 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:00:00 volumio volumio[1281]: updateProgress Apr 30 05:00:00 volumio volumio[1281]: { Apr 30 05:00:00 volumio volumio[1281]: downloadSpeed: '', Apr 30 05:00:00 volumio volumio[1281]: eta: '5m', Apr 30 05:00:00 volumio volumio[1281]: progress: 30, Apr 30 05:00:00 volumio volumio[1281]: status: 'Creating backup' Apr 30 05:00:00 volumio volumio[1281]: } Apr 30 05:00:00 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:00 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5202. Apr 30 05:00:02 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:02 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:02 volumio go-librespot[10959]: Librespot-go daemon starting... Apr 30 05:00:02 volumio go-librespot[10959]: time="2025-04-30T05:00:02Z" level=info msg="generated new device id: fcbd7e8eb7f4423f2a5d2bd695f79be1a1537971" Apr 30 05:00:02 volumio go-librespot[10959]: time="2025-04-30T05:00:02Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:02 volumio go-librespot[10959]: time="2025-04-30T05:00:02Z" level=debug msg="obtained new client token: AAB9jUPhjNJkAu3T+Yj0rXajtpsUfFK2NBbMl0/JkbMweopEjVpUXZuPYzcv29igQT7WgLvoKxyLVtkHaPHldOTF1NHLS1Tp9CPqrAvG1TIAOKqs0eVcrnlouCn1ZeDHHxhiDWcsP7LAa/3F6jjTlntusuJJ/ceI33COGqlncH2DjX035c4ly84i54uRz8Q5I0Bp/3uAGbGO5eDub5yMecle7xphfVQscrS784Vw/KaMIp9M/TNhl6xy9huJeu8=" Apr 30 05:00:03 volumio go-librespot[10959]: time="2025-04-30T05:00:03Z" 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]" Apr 30 05:00:03 volumio go-librespot[10959]: time="2025-04-30T05:00:03Z" level=debug msg="completed keyexchange" Apr 30 05:00:03 volumio go-librespot[10959]: time="2025-04-30T05:00:03Z" level=debug msg="completed challenge" Apr 30 05:00:03 volumio go-librespot[10959]: time="2025-04-30T05:00:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:03 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:03 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:06 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:06 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5203. Apr 30 05:00:06 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:06 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:06 volumio go-librespot[10971]: Librespot-go daemon starting... Apr 30 05:00:06 volumio go-librespot[10971]: time="2025-04-30T05:00:06Z" level=info msg="generated new device id: 3d4967e80287ce3de7699b14e6e4c7bdbbbad9f7" Apr 30 05:00:06 volumio go-librespot[10971]: time="2025-04-30T05:00:06Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:06 volumio go-librespot[10971]: time="2025-04-30T05:00:06Z" level=debug msg="obtained new client token: AACklpEc/6bfoRIPDIBDZsRVRfQAUir8eLmaZ+tCzFgDGw5FF4Mk49G+OyovBphC54KV4b02tM0MdfbRKoF98StuXHHufxg7VcHVRHlCR45U/us+9X3ZdJoaEaKfydY6omFNigPe8cSoKu4nKZyVfayWI9TXt2N7IZjGzGOwd5heIifO7ToQGqrspsXBtrEtcRIjVtTzfEbFD7YdyQsU2JXjfVVGCwB7PDdsxEQetvCe7v7qGL/Uf4TWw4w9yCQ=" Apr 30 05:00:07 volumio go-librespot[10971]: time="2025-04-30T05:00:07Z" 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]" Apr 30 05:00:07 volumio go-librespot[10971]: time="2025-04-30T05:00:07Z" level=debug msg="completed keyexchange" Apr 30 05:00:07 volumio go-librespot[10971]: time="2025-04-30T05:00:07Z" level=debug msg="completed challenge" Apr 30 05:00:07 volumio go-librespot[10971]: time="2025-04-30T05:00:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:09 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:09 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:10 volumio volumio-remote-updater[664]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m" Apr 30 05:00:10 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:00:10 volumio volumio[1281]: updateProgress Apr 30 05:00:10 volumio volumio[1281]: { Apr 30 05:00:10 volumio volumio[1281]: downloadSpeed: '', Apr 30 05:00:10 volumio volumio[1281]: eta: '4m', Apr 30 05:00:10 volumio volumio[1281]: progress: 30, Apr 30 05:00:10 volumio volumio[1281]: status: 'Downloading new update' Apr 30 05:00:10 volumio volumio[1281]: } Apr 30 05:00:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5204. Apr 30 05:00:10 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:10 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:10 volumio go-librespot[11021]: Librespot-go daemon starting... Apr 30 05:00:10 volumio go-librespot[11021]: time="2025-04-30T05:00:10Z" level=info msg="generated new device id: ddd13889ef1e92f1a6d324f3394217647f40f6ce" Apr 30 05:00:10 volumio go-librespot[11021]: time="2025-04-30T05:00:10Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:10 volumio go-librespot[11021]: time="2025-04-30T05:00:10Z" level=debug msg="obtained new client token: AABCR3WRP0DlHNpeFO2tw+9pFrdiGcz1Gw+JAEUDu9fwT2BHEXWKIr7/tSh2PJ8ImoyI/iJ/o8NgB0UsBITshiBchISMMP+DoSgd/jOAE/EzHg22vMjrzsYKam56qVKojpg0aj1TwAGi2qf43ugGHSP26x+JXZc2N3psHKMbGpEKKv+nNCTSTAZf/fnchlLEzaxTWi/2l/DC0GzCeVPkKvNROr9ps0y49Kc6DgcdSeudctBcqpmMz0VPTuD466w=" Apr 30 05:00:11 volumio go-librespot[11021]: time="2025-04-30T05:00:11Z" 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]" Apr 30 05:00:11 volumio go-librespot[11021]: time="2025-04-30T05:00:11Z" level=debug msg="completed keyexchange" Apr 30 05:00:11 volumio go-librespot[11021]: time="2025-04-30T05:00:11Z" level=debug msg="completed challenge" Apr 30 05:00:11 volumio go-librespot[11021]: time="2025-04-30T05:00:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:12 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:12 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5205. Apr 30 05:00:14 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:14 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:14 volumio go-librespot[11030]: Librespot-go daemon starting... Apr 30 05:00:14 volumio go-librespot[11030]: time="2025-04-30T05:00:14Z" level=info msg="generated new device id: 5c59f7824e3d21358056f3ecb73ec20cff66b38c" Apr 30 05:00:14 volumio go-librespot[11030]: time="2025-04-30T05:00:14Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:14 volumio go-librespot[11030]: time="2025-04-30T05:00:14Z" level=debug msg="obtained new client token: AACTy1o1wl5eakLcmCZi0Pc9p1y9vnrCbLFv6ypCpavEVYrcVDNVSuvKxTERKUqW42ymJHsrv/nSyM57TGcj3sjNRG+NnzezCvYtpTqwe+ORwUOa+0SoYIiqkrxhzmxojyeGGEGAMARq1ABsf43muZ8kPVnZfy5KgR4ERN+PrjjLfVaqG03ArWr9m1RcxKxJrR8t2EZcue9um/WD9r88cHRYn+Dseyq6zZqnU2L4/w1FDYLPzMYIwwAChqKA7Y4=" Apr 30 05:00:15 volumio go-librespot[11030]: time="2025-04-30T05:00:15Z" 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]" Apr 30 05:00:15 volumio go-librespot[11030]: time="2025-04-30T05:00:15Z" level=debug msg="completed keyexchange" Apr 30 05:00:15 volumio go-librespot[11030]: time="2025-04-30T05:00:15Z" level=debug msg="completed challenge" Apr 30 05:00:15 volumio go-librespot[11030]: time="2025-04-30T05:00:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:15 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:15 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:18 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:18 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5206. Apr 30 05:00:18 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:18 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:18 volumio go-librespot[11038]: Librespot-go daemon starting... Apr 30 05:00:18 volumio go-librespot[11038]: time="2025-04-30T05:00:18Z" level=info msg="generated new device id: 41b0a2e90cd64a06cae43d730b193e7081eed1e8" Apr 30 05:00:18 volumio go-librespot[11038]: time="2025-04-30T05:00:18Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:18 volumio go-librespot[11038]: time="2025-04-30T05:00:18Z" level=debug msg="obtained new client token: AABwhnDIj9rksFF5GC06SAPyNuDALawLN+OOo2oD1QyMbOwZldv4nBmJshdnBSBTGgYdCb4946C0CK1KhnRfMRkYJxjJ/iEwsTb5/2kzlNQoGzPU8Y1rNHz53pkDby9Zf9KOV2qFl5oOUWe5paNoLH8XSLBXMfEzwAv5Cx4MpoT78JOvsuIOzSPqBJ90LWEtU3JHgudorjU7gI+6hqA6c1EhQoWwcpSKMgxk86Mk586b8x0FnLgTMy6rhhExlUg=" Apr 30 05:00:19 volumio go-librespot[11038]: time="2025-04-30T05:00:19Z" 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]" Apr 30 05:00:19 volumio go-librespot[11038]: time="2025-04-30T05:00:19Z" level=debug msg="completed keyexchange" Apr 30 05:00:19 volumio go-librespot[11038]: time="2025-04-30T05:00:19Z" level=debug msg="completed challenge" Apr 30 05:00:19 volumio go-librespot[11038]: time="2025-04-30T05:00:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:21 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:21 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5207. Apr 30 05:00:22 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:22 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:22 volumio go-librespot[11099]: Librespot-go daemon starting... Apr 30 05:00:22 volumio go-librespot[11099]: time="2025-04-30T05:00:22Z" level=info msg="generated new device id: 3290b667e09abf173670afe65f438d751b56ba58" Apr 30 05:00:22 volumio go-librespot[11099]: time="2025-04-30T05:00:22Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:22 volumio go-librespot[11099]: time="2025-04-30T05:00:22Z" level=debug msg="obtained new client token: AABPmRtwTyKRflkoAAlT67ew5YQYhPs73Nv4poam9GuZjOoEp5/pC19sKnhYIWAaw1txG7BkohgXV3K+Nt5PiBpRA0WP2xzWQiwo8JI9lQkpH966npSZL+83yPga7AJPRdXtp09p+khRVbmVHAAsToZaygmCVDdDt5D4Mxv6E/i3m+qwNWxwVRIlbs9GA6mXjCwhPJ9b5oYnWkwAyLF3mJsEzukCF5CXCszvL2t65NTDreeLZJkWKFlQj6dVPds=" Apr 30 05:00:23 volumio go-librespot[11099]: time="2025-04-30T05:00:23Z" 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]" Apr 30 05:00:23 volumio go-librespot[11099]: time="2025-04-30T05:00:23Z" level=debug msg="completed keyexchange" Apr 30 05:00:23 volumio go-librespot[11099]: time="2025-04-30T05:00:23Z" level=debug msg="completed challenge" Apr 30 05:00:23 volumio go-librespot[11099]: time="2025-04-30T05:00:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:24 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:24 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5208. Apr 30 05:00:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:26 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:26 volumio go-librespot[11111]: Librespot-go daemon starting... Apr 30 05:00:26 volumio go-librespot[11111]: time="2025-04-30T05:00:26Z" level=info msg="generated new device id: 7ccec4cbedd749787d4d773489f33c853bafb31e" Apr 30 05:00:26 volumio go-librespot[11111]: time="2025-04-30T05:00:26Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:26 volumio go-librespot[11111]: time="2025-04-30T05:00:26Z" level=debug msg="obtained new client token: AABUkU+agchE4m7ftLeXyokDqSIhATxTrg/9pK2OxdGy4VY+kBRztSUgm3yzFisIYQDfwbpd0H1v6ChwGfJjtsOPHYwkgRszoHEbfbM3LU009+HxxcY7pdoe16s+KgEcWgYpwOI5dnf6+AkIPksvEklqPvFEcFLYDHesBn2s9XAGJS8pF/G9G90OCPbiYwHOBdEHCk/bNuEgOXqo6hNw0t5Xcjpg8pWPZaxC5KCbSaAasqFOWFbxBEtjumvA2yM=" Apr 30 05:00:27 volumio go-librespot[11111]: time="2025-04-30T05:00:27Z" 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]" Apr 30 05:00:27 volumio go-librespot[11111]: time="2025-04-30T05:00:27Z" level=debug msg="completed keyexchange" Apr 30 05:00:27 volumio go-librespot[11111]: time="2025-04-30T05:00:27Z" level=debug msg="completed challenge" Apr 30 05:00:27 volumio go-librespot[11111]: time="2025-04-30T05:00:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:27 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:27 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:30 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:30 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5209. Apr 30 05:00:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:30 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:30 volumio go-librespot[11120]: Librespot-go daemon starting... Apr 30 05:00:30 volumio go-librespot[11120]: time="2025-04-30T05:00:30Z" level=info msg="generated new device id: c9434e163ef1cac1a3bf85d0ffbbfd18dedd4de8" Apr 30 05:00:30 volumio go-librespot[11120]: time="2025-04-30T05:00:30Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:30 volumio go-librespot[11120]: time="2025-04-30T05:00:30Z" level=debug msg="obtained new client token: AAAHY+mjxGwnyHfOSHq/s2DQzYkng6p2VuxRiE0ikfUdwdwXq2aE47PG0k1fX2vWUVJOjyGF2w14Ka11tIzTYFVckscG6GNrvgauXMwzfHZ2WjIsVHI4e7MdMqlmDmXdSx0HZmzRMpqoSpoBUv5RE446nYA/an8O8XevdQe7zJ8mlPWpNYRRUOpYXGubVrE/GuL7pEEJay9JUzir6Jk7OjDMVOVcpegmKwg/AX3v0gpcMjaVt/fDrleMammgvf0=" Apr 30 05:00:31 volumio go-librespot[11120]: time="2025-04-30T05:00:31Z" 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]" Apr 30 05:00:31 volumio go-librespot[11120]: time="2025-04-30T05:00:31Z" level=debug msg="completed keyexchange" Apr 30 05:00:31 volumio go-librespot[11120]: time="2025-04-30T05:00:31Z" level=debug msg="completed challenge" Apr 30 05:00:31 volumio go-librespot[11120]: time="2025-04-30T05:00:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:33 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:33 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5210. Apr 30 05:00:34 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:34 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:34 volumio go-librespot[11141]: Librespot-go daemon starting... Apr 30 05:00:34 volumio go-librespot[11141]: time="2025-04-30T05:00:34Z" level=info msg="generated new device id: b21ca68d58b9ce75abef1c610fc2cbfeddbf6bb7" Apr 30 05:00:34 volumio go-librespot[11141]: time="2025-04-30T05:00:34Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:34 volumio go-librespot[11141]: time="2025-04-30T05:00:34Z" level=debug msg="obtained new client token: AAD1y4771y1zZXPo1G8dHahFE+n8r8uKKcb/xfYdqdoainsqIOqWrpSd5/VyOC+PlIq+zVey6vweLfLT1iztk7kVO1RnKitSO3Sr9Qa9tlpYPV6zpxOa+c7+I4pcCvGNRJm8YKxU6GHKJMtJMevi1o7trouKxT+9VJmCMYHYhHW1wk81nzHU/8htPr7DdbmB2X9OICFbd6MjBuBuBA5EY0kdVSWD2WSV9awO6RPwMxACa85+ZyOz9gdS10jFjes=" Apr 30 05:00:35 volumio go-librespot[11141]: time="2025-04-30T05:00:35Z" 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]" Apr 30 05:00:35 volumio go-librespot[11141]: time="2025-04-30T05:00:35Z" level=debug msg="completed keyexchange" Apr 30 05:00:35 volumio go-librespot[11141]: time="2025-04-30T05:00:35Z" level=debug msg="completed challenge" Apr 30 05:00:35 volumio go-librespot[11141]: time="2025-04-30T05:00:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:36 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:36 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5211. Apr 30 05:00:38 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:38 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:38 volumio go-librespot[11149]: Librespot-go daemon starting... Apr 30 05:00:38 volumio go-librespot[11149]: time="2025-04-30T05:00:38Z" level=info msg="generated new device id: ac83bef917c8f2b1db707ca3ea7dd5c5321a2ef4" Apr 30 05:00:38 volumio go-librespot[11149]: time="2025-04-30T05:00:38Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:38 volumio go-librespot[11149]: time="2025-04-30T05:00:38Z" level=debug msg="obtained new client token: AAAgthFEyT177IycrVUIRIYCi93+RUV+sZ1SwHSrPZvsYTx3mSdXdlNrpiVFgcvdMJ7twi1vS0dpTuyX1Gc5hwDcspKckgoL1oMzS8el+QsrJR89QlqjnEkhkfeUum4G6bMiPKGOzgmRtiksrkmXkSQ8II7uM72XTd2JYSF3juZEuQJQZInLE0ykdF4R56HeGivzXl5wwgXhWX6mjuE+quRGy+TkLs9GN/PcC3qwHsYjoTg/2Sw7OfaV4nshTD0=" Apr 30 05:00:39 volumio go-librespot[11149]: time="2025-04-30T05:00:39Z" 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]" Apr 30 05:00:39 volumio go-librespot[11149]: time="2025-04-30T05:00:39Z" level=debug msg="completed keyexchange" Apr 30 05:00:39 volumio go-librespot[11149]: time="2025-04-30T05:00:39Z" level=debug msg="completed challenge" Apr 30 05:00:39 volumio go-librespot[11149]: time="2025-04-30T05:00:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:39 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:39 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:42 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:42 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5212. Apr 30 05:00:42 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:42 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:42 volumio go-librespot[11171]: Librespot-go daemon starting... Apr 30 05:00:42 volumio go-librespot[11171]: time="2025-04-30T05:00:42Z" level=info msg="generated new device id: 8de52ed70541863e7df1a120055cae57d9274e13" Apr 30 05:00:42 volumio go-librespot[11171]: time="2025-04-30T05:00:42Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:42 volumio go-librespot[11171]: time="2025-04-30T05:00:42Z" level=debug msg="obtained new client token: AACDbkOD8/bOu1Or8wjVG8GpXyX8I7qpUR0/jU8RuiqAj5Cn6o6RMsZIjfq6425PumeJsu1El3ujX1CJTEpaDPudd2WujGaClnkWItROCfgZeFb/LUzoH6OEAYvken4PhzTpPmxQZp4NZgZ6cnPKeFjWn9nzOpeRMknxViO+CH+kfEdefTVfME2LWGyKUGgs6blDVHnEcwZtK9A4b7TOVfRl9djhyNjDaqKplKoKWUy73awi6HCYFwCPXmhiOC8=" Apr 30 05:00:43 volumio go-librespot[11171]: time="2025-04-30T05:00:43Z" 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]" Apr 30 05:00:43 volumio go-librespot[11171]: time="2025-04-30T05:00:43Z" level=debug msg="completed keyexchange" Apr 30 05:00:43 volumio go-librespot[11171]: time="2025-04-30T05:00:43Z" level=debug msg="completed challenge" Apr 30 05:00:43 volumio go-librespot[11171]: time="2025-04-30T05:00:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:45 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:45 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5213. Apr 30 05:00:46 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:46 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:46 volumio go-librespot[11183]: Librespot-go daemon starting... Apr 30 05:00:46 volumio go-librespot[11183]: time="2025-04-30T05:00:46Z" level=info msg="generated new device id: fd62f2545478c3ca9a9ae1df1e7cc1da8161f236" Apr 30 05:00:46 volumio go-librespot[11183]: time="2025-04-30T05:00:46Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:46 volumio go-librespot[11183]: time="2025-04-30T05:00:46Z" level=debug msg="obtained new client token: AACXto4zdX19XmmzRHXrkMhh1CUjebIoaE+zXxz8ddy7TltO3LIi0r+nl4allMmTwBxCTaLXKTpTTEvl/FjHqjhZL1FCVfkbwBbHW5VBW/2Cv6beKyd20yEnEvUv+YPHNz0JdMTwOaiE6j1zK9oNEGiGfTSEm0Wf4ta8CY6LubsC0l7FfQPztljv2qHzkALIxVdRRyqwEuUjRbYhayzEbgA+hYwhdKR8eWAB5VcJ5O4sI4yTSkkAMc+yMMKYZj4=" Apr 30 05:00:47 volumio go-librespot[11183]: time="2025-04-30T05:00:47Z" 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]" Apr 30 05:00:47 volumio go-librespot[11183]: time="2025-04-30T05:00:47Z" level=debug msg="completed keyexchange" Apr 30 05:00:47 volumio go-librespot[11183]: time="2025-04-30T05:00:47Z" level=debug msg="completed challenge" Apr 30 05:00:47 volumio go-librespot[11183]: time="2025-04-30T05:00:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:48 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:48 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5214. Apr 30 05:00:50 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:50 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:50 volumio go-librespot[11191]: Librespot-go daemon starting... Apr 30 05:00:50 volumio go-librespot[11191]: time="2025-04-30T05:00:50Z" level=info msg="generated new device id: a2d300fc7979867b24c619ed1f484adb1c2f9ab5" Apr 30 05:00:50 volumio go-librespot[11191]: time="2025-04-30T05:00:50Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:50 volumio go-librespot[11191]: time="2025-04-30T05:00:50Z" level=debug msg="obtained new client token: AAAy1rpfCpR8gH/KFB+4TBK6iGDl3ZeyCxYXkhyCMYi9xp3r0ekOmuE7PpjlECSaCpYXHHiNcrBkEkXkm8txYu6BLINike/ow3YOaNIWvXYHcqQuPzzehidLkpbaPwk1M9Lhlq9nHEu0vFBv7803mMoOPuS9c1cA2ep6nF5iyHyd8pHmb+BqIxVEjgFGXKSylbt/MtE7k7NICJKw2NfmQYJn7gg4EOSz2aNathTcJ9WS1k22u3h2Q19KzFd+jT8=" Apr 30 05:00:51 volumio go-librespot[11191]: time="2025-04-30T05:00:51Z" 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]" Apr 30 05:00:51 volumio go-librespot[11191]: time="2025-04-30T05:00:51Z" level=debug msg="completed keyexchange" Apr 30 05:00:51 volumio go-librespot[11191]: time="2025-04-30T05:00:51Z" level=debug msg="completed challenge" Apr 30 05:00:51 volumio go-librespot[11191]: time="2025-04-30T05:00:51Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:51 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:51 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:54 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:54 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5215. Apr 30 05:00:54 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:54 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:54 volumio go-librespot[11213]: Librespot-go daemon starting... Apr 30 05:00:54 volumio go-librespot[11213]: time="2025-04-30T05:00:54Z" level=info msg="generated new device id: 97a1eac4e69904e6bcb3cc1d777b5346ad6b7772" Apr 30 05:00:54 volumio go-librespot[11213]: time="2025-04-30T05:00:54Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:54 volumio go-librespot[11213]: time="2025-04-30T05:00:54Z" level=debug msg="obtained new client token: AADfN8KAs5dyjEHG3eu8jvKVJlk0TRDBMPNI0mOOoUChRphpg5kRWRdcxf1n2H953aP+Fp7cEbKfF46hTX+3CmVHxb7T6XaCsv2S4g73k77Du4Cv1dkcbKaxdafUftHf6VgaISQdnQypSYx4VITgYxwSxyW66xCBhCuZZv+SnhNh2lyawxDobHSzFrHd4PPgB7IspYjPvUAFMTTHKvLcRXo8SJT9JdFi5Q2IqXKdOgYlVrFNsIlbPZ70fguAFpI=" Apr 30 05:00:55 volumio go-librespot[11213]: time="2025-04-30T05:00:55Z" 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]" Apr 30 05:00:55 volumio go-librespot[11213]: time="2025-04-30T05:00:55Z" level=debug msg="completed keyexchange" Apr 30 05:00:55 volumio go-librespot[11213]: time="2025-04-30T05:00:55Z" level=debug msg="completed challenge" Apr 30 05:00:55 volumio go-librespot[11213]: time="2025-04-30T05:00:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:00:57 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:00:57 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:00:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:00:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5216. Apr 30 05:00:58 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:00:58 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:00:58 volumio go-librespot[11221]: Librespot-go daemon starting... Apr 30 05:00:58 volumio go-librespot[11221]: time="2025-04-30T05:00:58Z" level=info msg="generated new device id: 8485490707758e057e194d5432e3df24bd90b03f" Apr 30 05:00:58 volumio go-librespot[11221]: time="2025-04-30T05:00:58Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:00:58 volumio go-librespot[11221]: time="2025-04-30T05:00:58Z" level=debug msg="obtained new client token: AAAgEg6ljkMcSHoF1TV/D+pPezQlrHWoZgbn74uPmdmuTZ4ot2+fRoSn2kSNpF653KnjKXhRa7e5SnhFf82cpBaLQB2f9nY2Uz4CM5UbY+kZ9C7NPWiwhMDQVzw6m5i2xvh7Dxv078d1tJXFXfPQqYrYwTe9R1B/T/N6Q7K3rhaKvWVAFSEqkbbkmhyjRfju+8IdIAAnV1CCPKK2xFJRPtN5ceBOkAG7JR/8v2IodYQri84hFfg3yUV+/PAuTOo=" Apr 30 05:00:59 volumio go-librespot[11221]: time="2025-04-30T05:00:59Z" 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]" Apr 30 05:00:59 volumio go-librespot[11221]: time="2025-04-30T05:00:59Z" level=debug msg="completed keyexchange" Apr 30 05:00:59 volumio go-librespot[11221]: time="2025-04-30T05:00:59Z" level=debug msg="completed challenge" Apr 30 05:00:59 volumio go-librespot[11221]: time="2025-04-30T05:00:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:00:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:00:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:00 volumio volumio-remote-updater[664]: zsync done Apr 30 05:01:00 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:00 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5217. Apr 30 05:01:02 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:02 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:02 volumio go-librespot[11246]: Librespot-go daemon starting... Apr 30 05:01:02 volumio go-librespot[11246]: time="2025-04-30T05:01:02Z" level=info msg="generated new device id: 072ffb821aa8ecc97560b20b985f96201aeb7069" Apr 30 05:01:02 volumio go-librespot[11246]: time="2025-04-30T05:01:02Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:02 volumio go-librespot[11246]: time="2025-04-30T05:01:02Z" level=debug msg="obtained new client token: AACyi/LHPOWjMQVADe2aHfn1/kpggK7FbNP17707xKJbQKXiGqcrh40wNHw17NObrJm4y09TIiKXhTPDSr5yf6+BlwHM8wkyjmE+qSGcUV02NH9QaMSwzpkwS2rW6RpS262DK8yQfJJxDVWf8HleFcDRM/OThymSPKb3v9PgGFpFY5qXJ3P0gmeukDs9hNnCMfu5e1OVd7kgPtNM8iyT2S3qweedpBb8WRAKJaadUAu/w5AuPuPdq2TvCgaq0xQ=" Apr 30 05:01:03 volumio go-librespot[11246]: time="2025-04-30T05:01:03Z" 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]" Apr 30 05:01:03 volumio go-librespot[11246]: time="2025-04-30T05:01:03Z" level=debug msg="completed keyexchange" Apr 30 05:01:03 volumio go-librespot[11246]: time="2025-04-30T05:01:03Z" level=debug msg="completed challenge" Apr 30 05:01:03 volumio go-librespot[11246]: time="2025-04-30T05:01:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:03 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:03 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:06 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:06 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5218. Apr 30 05:01:06 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:06 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:06 volumio go-librespot[11258]: Librespot-go daemon starting... Apr 30 05:01:06 volumio go-librespot[11258]: time="2025-04-30T05:01:06Z" level=info msg="generated new device id: 3774d814a718b8d5a652ccb7283fffdcf51a14a5" Apr 30 05:01:06 volumio go-librespot[11258]: time="2025-04-30T05:01:06Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:06 volumio go-librespot[11258]: time="2025-04-30T05:01:06Z" level=debug msg="obtained new client token: AABl1sPjGRkzg74omTGXFkIpFPGoznOKT+zxiI6Qd8OWnU1DYCT5MgnV/RjIjFGMIW21GqVlEo0cf9hB2I0jRfX51HLMSprsTaD9OkY5oPGhfkJxscTyxfz8S4iXcG6svp8KXUz6wCLkLj+HBP027P85cRMVrIDMJuifB+V5fUavvSMsVL5Ef8OiI4dM5ne86cT/9EsdfJtqQ51NWJCtkAOszVPGYa9+jAEvNDRGc8YzyEza49+MwUxb/EHfv0s=" Apr 30 05:01:07 volumio go-librespot[11258]: time="2025-04-30T05:01:07Z" 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]" Apr 30 05:01:07 volumio go-librespot[11258]: time="2025-04-30T05:01:07Z" level=debug msg="completed keyexchange" Apr 30 05:01:07 volumio go-librespot[11258]: time="2025-04-30T05:01:07Z" level=debug msg="completed challenge" Apr 30 05:01:07 volumio go-librespot[11258]: time="2025-04-30T05:01:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:09 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:09 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5219. Apr 30 05:01:10 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:10 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:10 volumio go-librespot[11266]: Librespot-go daemon starting... Apr 30 05:01:10 volumio go-librespot[11266]: time="2025-04-30T05:01:10Z" level=info msg="generated new device id: a06d7e3ba855f39112669df752a6697016743a9a" Apr 30 05:01:10 volumio go-librespot[11266]: time="2025-04-30T05:01:10Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:10 volumio go-librespot[11266]: time="2025-04-30T05:01:10Z" level=debug msg="obtained new client token: AAAPibz8zCOtGEzgL/PCKnMKTvar28vCBFOzys4RtcIctxSSQpJn06yT6bkJWByaCWzhANRXZeUNItffCeRxdSLZlZPio9O0JsH5as543nqTeUi1ASlFbzhY6M7/8Gs9UD3RRS1q7nDkx+4GrxfdLR9Z6iBDiTsBLkFAZFs2Zkw863MrTqpJjPaaYNPfyZf1Eu5LI/cYIWHwyYbvjk/zs4vj3cFQapgHuvvUfw/so0yZVc9rwvdyP4kBFcWcrL4=" Apr 30 05:01:11 volumio go-librespot[11266]: time="2025-04-30T05:01:11Z" 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]" Apr 30 05:01:11 volumio go-librespot[11266]: time="2025-04-30T05:01:11Z" level=debug msg="completed keyexchange" Apr 30 05:01:11 volumio volumio-remote-updater[664]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m" Apr 30 05:01:11 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:11 volumio volumio[1281]: updateProgress Apr 30 05:01:11 volumio volumio[1281]: { Apr 30 05:01:11 volumio volumio[1281]: downloadSpeed: '', Apr 30 05:01:11 volumio volumio[1281]: eta: '2m', Apr 30 05:01:11 volumio volumio[1281]: progress: 80, Apr 30 05:01:11 volumio volumio[1281]: status: 'Downloading new update' Apr 30 05:01:11 volumio volumio[1281]: } Apr 30 05:01:11 volumio go-librespot[11266]: time="2025-04-30T05:01:11Z" level=debug msg="completed challenge" Apr 30 05:01:11 volumio go-librespot[11266]: time="2025-04-30T05:01:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:12 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:12 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:14 volumio volumio-remote-updater[664]: zsync done Apr 30 05:01:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5220. Apr 30 05:01:14 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:14 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:14 volumio go-librespot[11294]: Librespot-go daemon starting... Apr 30 05:01:14 volumio go-librespot[11294]: time="2025-04-30T05:01:14Z" level=info msg="generated new device id: 28b26cb6abbbb779871a835960adc98256761064" Apr 30 05:01:14 volumio go-librespot[11294]: time="2025-04-30T05:01:14Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:14 volumio go-librespot[11294]: time="2025-04-30T05:01:14Z" level=debug msg="obtained new client token: AABZubXmsJb6kGFXW83cbO0IBwJVv7VzZuzCYUK0X6edoPSdks84qJzsUffL6CAh+BmDnYZ23Wf1S+vXFcQrhmoZ5teCrHC0cWQBazRHx0a+hqikfvZOvcwxlyE7y+dUVMQp4ZPuO3hIOT9EpvKe0vfzbg6cHVgk9o2aSA8vcwS+xYBBDubC+q/xtHbFVPduqD4k7InJmfIEM+AvUXdh2jHtgOPdy2O04pHQ8PGLcw7HVdirfyqLBocaR0MH0uw=" Apr 30 05:01:15 volumio go-librespot[11294]: time="2025-04-30T05:01:15Z" 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]" Apr 30 05:01:15 volumio go-librespot[11294]: time="2025-04-30T05:01:15Z" level=debug msg="completed keyexchange" Apr 30 05:01:15 volumio go-librespot[11294]: time="2025-04-30T05:01:15Z" level=debug msg="completed challenge" Apr 30 05:01:15 volumio go-librespot[11294]: time="2025-04-30T05:01:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:15 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:15 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:18 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:18 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5221. Apr 30 05:01:18 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:18 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:18 volumio go-librespot[11302]: Librespot-go daemon starting... Apr 30 05:01:18 volumio go-librespot[11302]: time="2025-04-30T05:01:18Z" level=info msg="generated new device id: 000f266a36a62dcc18a397d34be25a1c1180e865" Apr 30 05:01:18 volumio go-librespot[11302]: time="2025-04-30T05:01:18Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:18 volumio go-librespot[11302]: time="2025-04-30T05:01:18Z" level=debug msg="obtained new client token: AABS937zpPKbTZkCmZKJ+O4yVWt5GzIDrEt1YfDeUOppOyz4YzC+SP7j875y9MtvLlc3E4bNbonKzCM1CUfZxtW/zWzxxACtadQNf0UolR6yroz6YnuaoSoOxXP85GKaJz4OT/TRbYBDhkA3c0bThcZsteWhyrBLRL121/dP6A94ubpqxbK96dZ6nfp/cqQxB4RiDT49oEWs4Jq33/TtDeb/D/tTGPra4eJKSL80jo1tbg2L/oP+RQSmXrpRc1E=" Apr 30 05:01:19 volumio go-librespot[11302]: time="2025-04-30T05:01:19Z" 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]" Apr 30 05:01:19 volumio go-librespot[11302]: time="2025-04-30T05:01:19Z" level=debug msg="completed keyexchange" Apr 30 05:01:19 volumio go-librespot[11302]: time="2025-04-30T05:01:19Z" level=debug msg="completed challenge" Apr 30 05:01:19 volumio go-librespot[11302]: time="2025-04-30T05:01:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:21 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:21 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5222. Apr 30 05:01:22 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:22 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:22 volumio go-librespot[11362]: Librespot-go daemon starting... Apr 30 05:01:22 volumio go-librespot[11362]: time="2025-04-30T05:01:22Z" level=info msg="generated new device id: 0c7d7f06b87bb22b4abddca36c42be6348edf8b6" Apr 30 05:01:22 volumio go-librespot[11362]: time="2025-04-30T05:01:22Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:22 volumio go-librespot[11362]: time="2025-04-30T05:01:22Z" level=debug msg="obtained new client token: AACCSf3HsMMFq/LuwXBKnOLLx/h0+wjgD7JFaymZknDX1kth+yGMx0/CKzAhdDoTMs4yPut8AcLh5sXDUJS+gx4GiwyLngJRBsOW6Hm0CbjT9YqgLSkdMu2jrkhmA+ULe51JrqkcJpuiMTPP7BBnwgW8Xjgm0DEU25QMqPer8mmDA0xq6xlGEH9mTvGCYH/TCI9R2XYDrdBQTq7DfgUYOEdbYyVmkqsBdNrm4PWUCoeQIaC7+tpxMdV654ZoQSI=" Apr 30 05:01:23 volumio go-librespot[11362]: time="2025-04-30T05:01:23Z" 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]" Apr 30 05:01:23 volumio go-librespot[11362]: time="2025-04-30T05:01:23Z" level=debug msg="completed keyexchange" Apr 30 05:01:23 volumio go-librespot[11362]: time="2025-04-30T05:01:23Z" level=debug msg="completed challenge" Apr 30 05:01:23 volumio go-librespot[11362]: time="2025-04-30T05:01:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:23 volumio volumio-remote-updater[664]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m" Apr 30 05:01:23 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:23 volumio volumio[1281]: updateProgress Apr 30 05:01:23 volumio volumio[1281]: { Apr 30 05:01:23 volumio volumio[1281]: downloadSpeed: '', Apr 30 05:01:23 volumio volumio[1281]: eta: '1m', Apr 30 05:01:23 volumio volumio[1281]: progress: 90, Apr 30 05:01:23 volumio volumio[1281]: status: 'Cleaning old files' Apr 30 05:01:23 volumio volumio[1281]: } Apr 30 05:01:23 volumio volumio-remote-updater[664]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory Apr 30 05:01:24 volumio volumio-remote-updater[664]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory Apr 30 05:01:24 volumio volumio-remote-updater[664]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s" Apr 30 05:01:24 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:24 volumio volumio[1281]: updateProgress Apr 30 05:01:24 volumio volumio[1281]: { Apr 30 05:01:24 volumio volumio[1281]: downloadSpeed: '', Apr 30 05:01:24 volumio volumio[1281]: eta: '30s', Apr 30 05:01:24 volumio volumio[1281]: progress: 95, Apr 30 05:01:24 volumio volumio[1281]: status: 'Finalizing update' Apr 30 05:01:24 volumio volumio[1281]: } Apr 30 05:01:24 volumio volumio-remote-updater[664]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.804\" version. System restart required." Apr 30 05:01:24 volumio volumio-remote-updater[664]: No test mode Apr 30 05:01:24 volumio volumio-remote-updater[664]: No alpha test mode Apr 30 05:01:24 volumio volumio-remote-updater[664]: [2025-04-30 05:01:24] [disconnect] Disconnect close local:[1000] remote:[1000] Apr 30 05:01:24 volumio volumio-remote-updater[664]: [2025-04-30 05:01:24] [info] asio async_write error: system:32 (Broken pipe) Apr 30 05:01:24 volumio volumio-remote-updater[664]: [2025-04-30 05:01:24] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error) Apr 30 05:01:24 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:24 volumio volumio[1281]: updateReady Apr 30 05:01:24 volumio volumio[1281]: { Apr 30 05:01:24 volumio volumio[1281]: changeLogLink: 'http://volumio.org', Apr 30 05:01:24 volumio volumio[1281]: description: '

FIXES



 



NEW ADDITIONS



 

 

', Apr 30 05:01:24 volumio volumio[1281]: title: 'Update v3.804', Apr 30 05:01:24 volumio volumio[1281]: updateavailable: true Apr 30 05:01:24 volumio volumio[1281]: } Apr 30 05:01:24 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Apr 30 05:01:24 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Apr 30 05:01:24 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Apr 30 05:01:24 volumio systemd[1]: Stopped volumio-remote-updater.service. Apr 30 05:01:24 volumio systemd[1]: Started volumio-remote-updater.service. Apr 30 05:01:24 volumio volumio-remote-updater[11383]: Error: No active session Apr 30 05:01:24 volumio volumio-remote-updater[11383]: [2025-04-30 05:01:24] [connect] Successful connection Apr 30 05:01:24 volumio volumio[1281]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Apr 30 05:01:24 volumio volumio-remote-updater[11383]: [2025-04-30 05:01:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745989284 101 Apr 30 05:01:24 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:24 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:25 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:26 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5223. Apr 30 05:01:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:26 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:26 volumio go-librespot[11390]: Librespot-go daemon starting... Apr 30 05:01:26 volumio go-librespot[11390]: time="2025-04-30T05:01:26Z" level=info msg="generated new device id: c666d1399404f218c07e6a00dc722f042d7f3c59" Apr 30 05:01:26 volumio go-librespot[11390]: time="2025-04-30T05:01:26Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:26 volumio go-librespot[11390]: time="2025-04-30T05:01:26Z" level=debug msg="obtained new client token: AADX1gmOg2kFEav6sWgQIcsY/1jdwKPH0EPd1G0AZdxoTrgoXFBgtiorPLl4+W2MKlolJnAQ+R6lhw8sbcxHKY4WHwdgaEQRjPLwL1DUN6I2IoFu2u0D3lBf/HiYAqBMiuLBo+k8e67oXkDnkCatcyONuu9Cd1jpPgWgWcToAN7cS0ObxSY/wL8UAujlXpDG2uh63Zv7SeKvlBHinIxaLn87173PO7m33fyvgvnDrQkeYWtySKnifDO4mpKCO+0=" Apr 30 05:01:27 volumio go-librespot[11390]: time="2025-04-30T05:01:27Z" 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]" Apr 30 05:01:27 volumio go-librespot[11390]: time="2025-04-30T05:01:27Z" level=debug msg="completed keyexchange" Apr 30 05:01:27 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:27 volumio go-librespot[11390]: time="2025-04-30T05:01:27Z" level=debug msg="completed challenge" Apr 30 05:01:27 volumio go-librespot[11390]: time="2025-04-30T05:01:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:27 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:27 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:28 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:29 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:30 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:30 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:30 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5224. Apr 30 05:01:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:30 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:30 volumio go-librespot[11398]: Librespot-go daemon starting... Apr 30 05:01:30 volumio go-librespot[11398]: time="2025-04-30T05:01:30Z" level=info msg="generated new device id: e86df9922fe2fe7a71a9eb13c55d6d450b613513" Apr 30 05:01:30 volumio go-librespot[11398]: time="2025-04-30T05:01:30Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:30 volumio go-librespot[11398]: time="2025-04-30T05:01:30Z" level=debug msg="obtained new client token: AAAWgDhNZXwmwqfUDn64ixWPbKHOm/7hQf0GEc3dQT6RawJ1jxYg9IK4iyHJl/Bven5WD6+5sEFcZ7OxduXXaFjPwYX8stsTVReQ6qYRbHodbA3EOMPIfctpXX7eqOo0/WA5Yr9oRCFHV/EDjNt1Nbp2//8vgxa+njYir8aiUPmJ/WcjHpuWNbnd3wVItOIoDMN33XwJhZQpc+OkwpHo8n2np79mXOXNAwQbETdHoMfqUAXuGsUqxTB0vv2tGWc=" Apr 30 05:01:31 volumio go-librespot[11398]: time="2025-04-30T05:01:31Z" 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]" Apr 30 05:01:31 volumio go-librespot[11398]: time="2025-04-30T05:01:31Z" level=debug msg="completed keyexchange" Apr 30 05:01:31 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:31 volumio go-librespot[11398]: time="2025-04-30T05:01:31Z" level=debug msg="completed challenge" Apr 30 05:01:31 volumio go-librespot[11398]: time="2025-04-30T05:01:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:32 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:33 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:33 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:33 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:34 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5225. Apr 30 05:01:34 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:34 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:34 volumio go-librespot[11420]: Librespot-go daemon starting... Apr 30 05:01:34 volumio go-librespot[11420]: time="2025-04-30T05:01:34Z" level=info msg="generated new device id: 7e7dd4668f8f041d5f157c36278a094117c86ee5" Apr 30 05:01:34 volumio go-librespot[11420]: time="2025-04-30T05:01:34Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:34 volumio go-librespot[11420]: time="2025-04-30T05:01:34Z" level=debug msg="obtained new client token: AACsldhZRR4dga0o81WaqLnoGwuwc09oc3irLn3m++PESbfZS1x1JEOZqySvvh3cq49g1ZEKo981KD5X7ASM/xa0hqp9jgJ353YjvjwCW0KIN3wPE0py80hy0nvDlXDQi5m/GzPZFvmcLzWEwsfy6kzJGvO9CyxA+NwvTXuz9TQi9XLyB26/NUGy2DHg4GpRBicxI+9ZGEuGHi/izbrJxaLfkYXYW/5dwH7QruPVFA26X0y2XBX6ksz9oHVazdY=" Apr 30 05:01:35 volumio go-librespot[11420]: time="2025-04-30T05:01:35Z" 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]" Apr 30 05:01:35 volumio go-librespot[11420]: time="2025-04-30T05:01:35Z" level=debug msg="completed keyexchange" Apr 30 05:01:35 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:35 volumio go-librespot[11420]: time="2025-04-30T05:01:35Z" level=debug msg="completed challenge" Apr 30 05:01:35 volumio go-librespot[11420]: time="2025-04-30T05:01:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:36 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:36 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:36 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:37 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:38 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 30 05:01:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5226. Apr 30 05:01:38 volumio systemd[1]: Stopped go-librespot Daemon. Apr 30 05:01:38 volumio systemd[1]: Started go-librespot Daemon. Apr 30 05:01:38 volumio go-librespot[11460]: Librespot-go daemon starting... Apr 30 05:01:38 volumio go-librespot[11460]: time="2025-04-30T05:01:38Z" level=info msg="generated new device id: 92c0763fcc923a47a8605b86eb0283dbbf1ef766" Apr 30 05:01:38 volumio go-librespot[11460]: time="2025-04-30T05:01:38Z" level=debug msg="stored credentials found for adonzell" Apr 30 05:01:38 volumio go-librespot[11460]: time="2025-04-30T05:01:38Z" level=debug msg="obtained new client token: AAAucuHYh9l+3Ef8oubb6yktkL2tE6MU/ApNNK/UFTjd1nY2fZbJo9snLSD8FNYamDm6aGcvVrmB1cvoeRMbrG9+6dmNdZDnffAwK2mVxs7ornkq6SsmiiYiLYTKdlvGfBhr3bLjY5T9jx1t2TBMBZoStpHgXn+EqES2scwbTOmNHgxKmzj9BzBp9ellgfWFrJpghXXyxE2V1HKY7PEfnIzHDy+zz1JMndSIyJyWSVAp2nhRE61c1VeJ1ZCyCHs=" Apr 30 05:01:39 volumio go-librespot[11460]: time="2025-04-30T05:01:39Z" 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]" Apr 30 05:01:39 volumio go-librespot[11460]: time="2025-04-30T05:01:39Z" level=debug msg="completed keyexchange" Apr 30 05:01:39 volumio volumio[1281]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Apr 30 05:01:39 volumio go-librespot[11460]: time="2025-04-30T05:01:39Z" level=debug msg="completed challenge" Apr 30 05:01:39 volumio go-librespot[11460]: time="2025-04-30T05:01:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 30 05:01:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 30 05:01:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 30 05:01:39 volumio volumio[1281]: info: Initializing connection to go-librespot Websocket Apr 30 05:01:39 volumio volumio[1281]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 30 05:01:40 volumio volumio[1281]: info: CoreCommandRouter::Close All Modals sent Apr 30 05:01:40 volumio volumio[1281]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Apr 30 05:01:40 volumio volumio[1281]: info: PLUGIN onReboot : networkfs Apr 30 05:01:40 volumio sudo[11469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/DSD_MUSIC Apr 30 05:01:40 volumio sudo[11469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 05:01:40 volumio systemd[1]: mnt-NAS-DSD_MUSIC.mount: Succeeded. Apr 30 05:01:40 volumio sudo[11469]: pam_unix(sudo:session): session closed for user root Apr 30 05:01:40 volumio sudo[11477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Leftovers Apr 30 05:01:40 volumio sudo[11477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 05:01:40 volumio systemd[1]: mnt-NAS-Leftovers.mount: Succeeded. Apr 30 05:01:40 volumio sudo[11477]: pam_unix(sudo:session): session closed for user root Apr 30 05:01:40 volumio sudo[11481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Music Apr 30 05:01:40 volumio sudo[11481]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 05:01:40 volumio sudo[11481]: pam_unix(sudo:session): session closed for user root Apr 30 05:01:40 volumio volumio[1281]: umount.nfs: /mnt/NAS/Music: device is busy Apr 30 05:01:40 volumio volumio[1281]: error: Cannot umount share Music : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/Music Apr 30 05:01:40 volumio volumio[1281]: umount.nfs: /mnt/NAS/Music: device is busy Apr 30 05:01:40 volumio volumio[1281]: info: PLUGIN onReboot : audiophonicsonoff Apr 30 05:01:40 volumio volumio[1281]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 05:01:40 volumio volumio[1281]: TypeError: Cannot read property 'writeSync' of undefined Apr 30 05:01:40 volumio volumio[1281]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Apr 30 05:01:40 volumio volumio[1281]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Apr 30 05:01:40 volumio volumio[1281]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Apr 30 05:01:40 volumio volumio[1281]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 30 05:01:40 volumio volumio[1281]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 30 05:01:40 volumio volumio[1281]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Apr 30 05:01:40 volumio volumio[1281]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Apr 30 05:01:40 volumio volumio[1281]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/updater_comm/index.js:156:35) Apr 30 05:01:40 volumio volumio[1281]: at listOnTimeout (internal/timers.js:554:17) Apr 30 05:01:40 volumio volumio[1281]: at processTimers (internal/timers.js:497:7) Apr 30 05:01:40 volumio volumio[1281]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 05:01:40 volumio sudo[11493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 05:00 Apr 30 05:01:40 volumio sudo[11493]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"