-- Logs begin at Sat 2024-10-12 18:56:00 CEST, end at Sat 2024-10-12 19:00:59 CEST. -- Oct 12 18:59:00 volumio go-librespot[2541]: time="2024-10-12T18:59:00+02:00" level=debug msg="completed challenge" Oct 12 18:59:02 volumio go-librespot[2541]: time="2024-10-12T18:59:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:02 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:02 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Oct 12 18:59:05 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:05 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:05 volumio go-librespot[2594]: Librespot-go daemon starting... Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=info msg="generated new device id: 54b7cd78eda18f3cf3b91cbb79ab88479ae413e3" Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:05 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=debug msg="new websocket client" Oct 12 18:59:05 volumio volumio[965]: info: Connection to go-librespot Websocket established Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=debug msg="obtained new client token: AAByUXWF2ZRi0nJ6/PN/krkAE4cH6In/pDZeSrvLZpdOzlLBvMPOVyjMiLYIUU3YcGSl7yhTnF3UvmARB/8Rg2MVLP6QlfDsmdzH34VUw9bjYjxe0cy04U4rcAq0ogqdVxSk1PBrjXwCV4AVZuMU1wPGRK/+ysOCpshNHjnDpxA4dc0UXhbkOp6ENge3+30cByKa9Q84xmLp6UIWMX+ljO9TbUi4/caSW4nBkiV9obQ5oHfLDacMSQBmUDM=" Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=debug msg="completed challenge" Oct 12 18:59:05 volumio go-librespot[2594]: time="2024-10-12T18:59:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:05 volumio volumio[965]: info: Connection to go-librespot Websocket closed Oct 12 18:59:08 volumio volumio[965]: info: Getting Spotify volume Oct 12 18:59:08 volumio volumio[965]: (node:965) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:08 volumio volumio[965]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Oct 12 18:59:08 volumio volumio[965]: (node:965) 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: 7) Oct 12 18:59:08 volumio volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 12 18:59:08 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 18:59:08 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 18:59:08 volumio volumio[965]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Oct 12 18:59:08 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:08 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Oct 12 18:59:09 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:09 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:09 volumio go-librespot[2603]: Librespot-go daemon starting... Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=info msg="generated new device id: 702bcf7017a8d571545ec2999f1942ff8efc1426" Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=debug msg="obtained new client token: AABojUZ7AzaRt+Gi8lEXjRydidmDkWsi7rNWWEsXUYKdwPw1kBn3LQlcgz8fqRTxKoCxVdL6Kf+yriGgPb+TwMNy618v20S7y9BQqTmnlyMsYyQ6xIVMGYCIxaAELm7UxaeGmN+DtOStHuRn9pVex3p5TevQZDB8cM1PfXVT838zszczPI3d7EwwbOkKxrjuV2c1Jec2CwEYpIplZ7KfqfrzLll4D08SIz0ncF5hHQBxeoix7PcBBdeXyoA=" Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=debug msg="completed challenge" Oct 12 18:59:09 volumio go-librespot[2603]: time="2024-10-12T18:59:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:11 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:11 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Oct 12 18:59:13 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:13 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:13 volumio go-librespot[2611]: Librespot-go daemon starting... Oct 12 18:59:13 volumio go-librespot[2611]: time="2024-10-12T18:59:13+02:00" level=info msg="generated new device id: 30dc2cb0af9fc93929109b60bc87ff33c5930d90" Oct 12 18:59:13 volumio go-librespot[2611]: time="2024-10-12T18:59:13+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:13 volumio go-librespot[2611]: time="2024-10-12T18:59:13+02:00" level=debug msg="obtained new client token: AADe/bLyOBLuzvzDhPjr52QCM5Q3ACHXL3r4Dchnlu6KmBrzMiZvHRICUl2ZcejjDRbDieiqq5k7ushOQj2c0DNAw2B5ei5jce6B0Nxi2R5PD6eEy1AApyl9pc7W62Sf6uh6Mxo3ad4bkiGhBISNQzFEMAV9jYH1UXLJ5vWKP6FgA8kgAQVOcEXw4fTasGBx+iSpQGs5CMdziHREJBQFCfnRkl6mhi6QnejSi9p7ML8faDKUWEWj6Yqk6sg=" Oct 12 18:59:13 volumio go-librespot[2611]: time="2024-10-12T18:59:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:13 volumio go-librespot[2611]: time="2024-10-12T18:59:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.9:4070: connect: connection refused" Oct 12 18:59:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:14 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:14 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Oct 12 18:59:16 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:16 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:16 volumio go-librespot[2619]: Librespot-go daemon starting... Oct 12 18:59:16 volumio go-librespot[2619]: time="2024-10-12T18:59:16+02:00" level=info msg="generated new device id: b806e9533e029d35463ca60eb9ad0d2eac330a48" Oct 12 18:59:16 volumio go-librespot[2619]: time="2024-10-12T18:59:16+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:16 volumio go-librespot[2619]: time="2024-10-12T18:59:16+02:00" level=debug msg="obtained new client token: AADFgpVKOvR0ezOzwiWjvrqHToWUr9YQrLT2ctcei99GteGD78v67HEaSZ2dr81LFzmKxIz095HQAgrPpGKnOE24VSay2n4W2uKtXk65q2D7uHIMZM9f01Vd4RSEC613MK3I4lUJ6/W1xqAhPxRpO8iDcgEW2wfvQnAFbdfdCrOYPFR+mCr6vsRqBJRjttNN9nZcnK0eoO/V6R/ZKyZMI34NMDS6YVWRLyen73e9tr2ch9KZKsP6GNaSRIU6Bw==" Oct 12 18:59:16 volumio go-librespot[2619]: time="2024-10-12T18:59:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:16 volumio go-librespot[2619]: time="2024-10-12T18:59:16+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:17 volumio go-librespot[2619]: time="2024-10-12T18:59:17+02:00" level=debug msg="completed challenge" Oct 12 18:59:17 volumio go-librespot[2619]: time="2024-10-12T18:59:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:17 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:17 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Oct 12 18:59:20 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:20 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:20 volumio go-librespot[2628]: Librespot-go daemon starting... Oct 12 18:59:20 volumio go-librespot[2628]: time="2024-10-12T18:59:20+02:00" level=info msg="generated new device id: 393851eef6d599b05360838f7281313e3a410289" Oct 12 18:59:20 volumio go-librespot[2628]: time="2024-10-12T18:59:20+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:20 volumio go-librespot[2628]: time="2024-10-12T18:59:20+02:00" level=debug msg="obtained new client token: AAA41PJnHVlg1QgF04UpE/bJGSgG5OhjOBZuQh5KZMKIBJnRIKalI3cb20aokfIIGVpwk9JFsopZ9VIWV3L2N7nxOhZBVoaeC+9LHhcgRWjZhRuWZNJvx+ZDywiGDrr4L5UiKP4DjSrXQfGY/YKMLxKlYa3zitZLql1Djh3IFkn9j+jnV49XboinRH2ZxwcMbSL9cwjrXKiTrGgSD7V7VC0TYtGNELfU+Cp/wkWkbHEQ5IUaKoz7A6riW/v3BA==" Oct 12 18:59:20 volumio go-librespot[2628]: time="2024-10-12T18:59:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 12 18:59:20 volumio go-librespot[2628]: time="2024-10-12T18:59:20+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:20 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:20 volumio go-librespot[2628]: time="2024-10-12T18:59:20+02:00" level=debug msg="new websocket client" Oct 12 18:59:20 volumio volumio[965]: info: Connection to go-librespot Websocket established Oct 12 18:59:21 volumio go-librespot[2628]: time="2024-10-12T18:59:21+02:00" level=debug msg="completed challenge" Oct 12 18:59:21 volumio go-librespot[2628]: time="2024-10-12T18:59:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:21 volumio volumio[965]: info: Connection to go-librespot Websocket closed Oct 12 18:59:23 volumio volumio[965]: info: Getting Spotify volume Oct 12 18:59:23 volumio volumio[965]: (node:965) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:23 volumio volumio[965]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Oct 12 18:59:23 volumio volumio[965]: (node:965) 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: 8) Oct 12 18:59:23 volumio volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 12 18:59:23 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 18:59:23 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 18:59:23 volumio volumio[965]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Oct 12 18:59:24 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:24 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Oct 12 18:59:24 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:24 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:24 volumio go-librespot[2636]: Librespot-go daemon starting... Oct 12 18:59:24 volumio go-librespot[2636]: time="2024-10-12T18:59:24+02:00" level=info msg="generated new device id: af3cc3f62685fc5f1d3ff570e82bc78d2db1ab70" Oct 12 18:59:24 volumio go-librespot[2636]: time="2024-10-12T18:59:24+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:24 volumio go-librespot[2636]: time="2024-10-12T18:59:24+02:00" level=debug msg="obtained new client token: AAA4YGtzrIK8rg/L1Vzh1ByxUgqcq+edjw0zeLI1HK6/R/hhQxzJA58XzOe5Z2hsbAf08OuhBlnYyeRjaWKX+O4xGxNPj9LbVmNY+eNBf+fExKNuJdHOal+jTE6shEnEu3265KVDj9vD4Ix7rO7B19XvRXolaGXBbQos5Bu4iSY0iu8rEdV4GizTFL8zM0YnHTg9f24Ei8k52jRiM4m9Rm3rRZlmdcpJQtKbPS06NOduCFCftRH7ueENCiwZtA==" Oct 12 18:59:24 volumio go-librespot[2636]: time="2024-10-12T18:59:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:24 volumio go-librespot[2636]: time="2024-10-12T18:59:24+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:25 volumio go-librespot[2636]: time="2024-10-12T18:59:25+02:00" level=debug msg="completed challenge" Oct 12 18:59:25 volumio go-librespot[2636]: time="2024-10-12T18:59:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:27 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:27 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Oct 12 18:59:28 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:28 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:28 volumio go-librespot[2645]: Librespot-go daemon starting... Oct 12 18:59:28 volumio go-librespot[2645]: time="2024-10-12T18:59:28+02:00" level=info msg="generated new device id: 97cfab60ccfc5c80eb3a7f36675e34278ee365da" Oct 12 18:59:28 volumio go-librespot[2645]: time="2024-10-12T18:59:28+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:28 volumio go-librespot[2645]: time="2024-10-12T18:59:28+02:00" level=debug msg="obtained new client token: AADBooAxOxiCaCnQY5h7He06hxYT2A0vqHTMV+t+AO+ijbGipNr6w0yVDe1W7ZdbYjIH0F57Tj3O2t3XKDqV0ddnXO8J9oN3yUoD4m5gd+CWruFNH0z7tXb9TzLkPaFyD9nEu1i/rsYq42aAGQHc0uTVC+qaNtcRrEg0YCHyCLquy55CdZyBa1DAd5bauTAV2hNt4mLkcdngixGGKLJD2HZI2Z02IzeZZkhEfhM6qj53WpcF8Vp4wDC/JL/d9Q==" Oct 12 18:59:28 volumio go-librespot[2645]: time="2024-10-12T18:59:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:28 volumio go-librespot[2645]: time="2024-10-12T18:59:28+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:29 volumio go-librespot[2645]: time="2024-10-12T18:59:29+02:00" level=debug msg="completed challenge" Oct 12 18:59:29 volumio go-librespot[2645]: time="2024-10-12T18:59:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:30 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:30 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Oct 12 18:59:32 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:32 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:32 volumio go-librespot[2653]: Librespot-go daemon starting... Oct 12 18:59:32 volumio go-librespot[2653]: time="2024-10-12T18:59:32+02:00" level=info msg="generated new device id: b090febb407b32057ce10be14f891674c18b0240" Oct 12 18:59:32 volumio go-librespot[2653]: time="2024-10-12T18:59:32+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:32 volumio go-librespot[2653]: time="2024-10-12T18:59:32+02:00" level=debug msg="obtained new client token: AADcX9iuZi9N1bCmfusvOMipBbCl28kD1MdoxfhLWzciko37rcmvtr0QKdZaxhPJapuFjbSeQrdDNYAkDF2zXfQcQH02MjJls98WIChw5uHl0oClpu5Z5mKkQvQBkySGkRqSmRXwzpKX8O/tZmvvuKyBje/LfeskpbZZ92o0rBiuYCWshgK3Z6G8MqENX6KIh9aXSiVHVx/497r7TQqURkT1h7GacxmdqlkUgvuo5YIb02u00c4OHbDA4j6G1g==" Oct 12 18:59:32 volumio go-librespot[2653]: time="2024-10-12T18:59:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:32 volumio go-librespot[2653]: time="2024-10-12T18:59:32+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:33 volumio go-librespot[2653]: time="2024-10-12T18:59:33+02:00" level=debug msg="completed challenge" Oct 12 18:59:33 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:33 volumio go-librespot[2653]: time="2024-10-12T18:59:33+02:00" level=debug msg="new websocket client" Oct 12 18:59:33 volumio volumio[965]: info: Connection to go-librespot Websocket established Oct 12 18:59:33 volumio go-librespot[2653]: time="2024-10-12T18:59:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:33 volumio volumio[965]: info: Connection to go-librespot Websocket closed Oct 12 18:59:36 volumio volumio[965]: info: Getting Spotify volume Oct 12 18:59:36 volumio volumio[965]: (node:965) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:36 volumio volumio[965]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Oct 12 18:59:36 volumio volumio[965]: (node:965) 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: 9) Oct 12 18:59:36 volumio volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 12 18:59:36 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:36 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:36 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 18:59:36 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 18:59:36 volumio volumio[965]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Oct 12 18:59:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Oct 12 18:59:36 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:36 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:36 volumio go-librespot[2661]: Librespot-go daemon starting... Oct 12 18:59:36 volumio go-librespot[2661]: time="2024-10-12T18:59:36+02:00" level=info msg="generated new device id: 8ff83f9af5d99ade3b94d524276b4b542db0e6b6" Oct 12 18:59:36 volumio go-librespot[2661]: time="2024-10-12T18:59:36+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:36 volumio go-librespot[2661]: time="2024-10-12T18:59:36+02:00" level=debug msg="obtained new client token: AAAnrXZniJh69Q6sCusr3ldn2kbR5JBVXzkGR8bnXW8DYk6zlxsMni/PB282yanrFEwZ2tlQ0J9PT3mhw8nCbJrqJG7c1wexQFNAjRht742WxD1hj91EBSq19s2YmpH/p5wFYP8WbleNPvyceP8NawguENCEe3dl6qDFQp4T+eEprC0trdfQ5sFG/6Ac5t+bjyhJB2hmj1R2zuInEuFpaHO3PjZnXq8OkpFqcLYPsORHz9oslKTXuy4yKTiRTA==" Oct 12 18:59:36 volumio go-librespot[2661]: time="2024-10-12T18:59:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 12 18:59:36 volumio go-librespot[2661]: time="2024-10-12T18:59:36+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:37 volumio go-librespot[2661]: time="2024-10-12T18:59:37+02:00" level=debug msg="completed challenge" Oct 12 18:59:37 volumio go-librespot[2661]: time="2024-10-12T18:59:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:39 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:39 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Oct 12 18:59:40 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:40 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:40 volumio go-librespot[2669]: Librespot-go daemon starting... Oct 12 18:59:40 volumio go-librespot[2669]: time="2024-10-12T18:59:40+02:00" level=info msg="generated new device id: 2bd96ebe44299f4e8388cb81c6dc9b4b9c8ece89" Oct 12 18:59:40 volumio go-librespot[2669]: time="2024-10-12T18:59:40+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:40 volumio go-librespot[2669]: time="2024-10-12T18:59:40+02:00" level=debug msg="obtained new client token: AAAzWXRkG5GhHZ1O7N2hcOHprQrM0ZAK761osmUQ4cCxdW+0MoHb0Ri/H2STxIIAYPN2lrkNPemoh2vhiqp6mBkXpidJxCejycYnpf6ig4N6apgrBxHtaWlwNjhRo2zid7y/Jz/AzvnMd00KF4yjcxTlSQqkmU0XlntTRXkaHBfwgwYnNw/Biab4Sq0/nZK5xfTOzEftRLOYCHXtMA95xhpNOYgn+dUmx1/q2RtfqBDU+c/YqI2/NiwDTfyu5w==" Oct 12 18:59:40 volumio go-librespot[2669]: time="2024-10-12T18:59:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:40 volumio go-librespot[2669]: time="2024-10-12T18:59:40+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:41 volumio go-librespot[2669]: time="2024-10-12T18:59:41+02:00" level=debug msg="completed challenge" Oct 12 18:59:41 volumio go-librespot[2669]: time="2024-10-12T18:59:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:42 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:42 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Oct 12 18:59:44 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:44 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:44 volumio go-librespot[2677]: Librespot-go daemon starting... Oct 12 18:59:44 volumio go-librespot[2677]: time="2024-10-12T18:59:44+02:00" level=info msg="generated new device id: 986f79b31ad79bca037729b3ce4bc6d7fa088876" Oct 12 18:59:44 volumio go-librespot[2677]: time="2024-10-12T18:59:44+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:44 volumio go-librespot[2677]: time="2024-10-12T18:59:44+02:00" level=debug msg="obtained new client token: AACQpuK0SA+xVRszCalD22EeHFFrH2ka9MVc5UGqeagmyLgHSCQjDsWj9LwAJT38NCsEVNK7Xnnc5DpMzhPyB0PcEhwUuSSXFP3ZEBAvduV9AJ3ktGjXzsBz2CuSxFN/pAsJu5z69+j4Gu9fwN4wTUImDOB3E9bK+Bmz0rwYGk1PRC7LnQu7lY9+NOvzcxwT4GQ/PvK85PkQx8uMqkAzw3c3q2K9dZ2nosWZmEAlKOQP3XpFDOUEWbX+f8pwLg==" Oct 12 18:59:44 volumio go-librespot[2677]: time="2024-10-12T18:59:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:44 volumio go-librespot[2677]: time="2024-10-12T18:59:44+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:45 volumio go-librespot[2677]: time="2024-10-12T18:59:45+02:00" level=debug msg="completed challenge" Oct 12 18:59:45 volumio go-librespot[2677]: time="2024-10-12T18:59:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:45 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:45 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:48 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:48 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Oct 12 18:59:48 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:48 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:48 volumio go-librespot[2687]: Librespot-go daemon starting... Oct 12 18:59:48 volumio go-librespot[2687]: time="2024-10-12T18:59:48+02:00" level=info msg="generated new device id: 55e9a14687afd840c36a4817b44d11aaa811c198" Oct 12 18:59:48 volumio go-librespot[2687]: time="2024-10-12T18:59:48+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:48 volumio go-librespot[2687]: time="2024-10-12T18:59:48+02:00" level=debug msg="obtained new client token: AACzR7G2Fz66yv5epKPxX9VYyFldO5Vi1JsMjU1gOTNGoRias7CDjvgDWl2lTjTzFUtQwW27pOZNCx0xYEfN5rB0UxIL+W0dYqBL8EZSpNgZ1p1JEDNwjlyIh68UPFnhMJ/r036dR7o7D8NJjrS47Y0jGHicQf49QTgkI6o9jXHlKX4E1oPgiyZKlcsn+6vgE5bz3Ak+OXz5Ppsgi9qF/kSv8fgsrPWnRy1J2YOTiic5z6wY0whz/xnL8lTDvw==" Oct 12 18:59:48 volumio go-librespot[2687]: time="2024-10-12T18:59:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:48 volumio go-librespot[2687]: time="2024-10-12T18:59:48+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:49 volumio go-librespot[2687]: time="2024-10-12T18:59:49+02:00" level=debug msg="completed challenge" Oct 12 18:59:49 volumio go-librespot[2687]: time="2024-10-12T18:59:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:51 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:51 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Oct 12 18:59:52 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:52 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:52 volumio go-librespot[2695]: Librespot-go daemon starting... Oct 12 18:59:52 volumio go-librespot[2695]: time="2024-10-12T18:59:52+02:00" level=info msg="generated new device id: af612255b0510b022883b7e0a76ab77cbee4a2b0" Oct 12 18:59:52 volumio go-librespot[2695]: time="2024-10-12T18:59:52+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:52 volumio go-librespot[2695]: time="2024-10-12T18:59:52+02:00" level=debug msg="obtained new client token: AACkyIEZYKuOy37nq28NST3Dxn5j3fr3TqICdCUYj5icpIr/xY3iva5tnVy/m3Yv+c91uR+DWJEMvJUYvxcQgNI40mglMmN3RQyB2TcTMss6+lCtfqayysCPJqOUnwHJpfmu2J8zeljjG1v7a0lLyo1JQvuHvZXRigK0EPjLhr8rC1hleFthgWyesoAGbII3zrv95RAkznae3B4wLNxS3bPIoD/ic6pnsWir6SH0/yyQhIMM80AQbeR6Mk/q0g==" Oct 12 18:59:52 volumio go-librespot[2695]: time="2024-10-12T18:59:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:52 volumio go-librespot[2695]: time="2024-10-12T18:59:52+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:53 volumio go-librespot[2695]: time="2024-10-12T18:59:53+02:00" level=debug msg="completed challenge" Oct 12 18:59:53 volumio go-librespot[2695]: time="2024-10-12T18:59:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:54 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:54 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 18:59:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 18:59:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Oct 12 18:59:56 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 18:59:56 volumio systemd[1]: Started go-librespot Daemon. Oct 12 18:59:56 volumio go-librespot[2703]: Librespot-go daemon starting... Oct 12 18:59:56 volumio go-librespot[2703]: time="2024-10-12T18:59:56+02:00" level=info msg="generated new device id: 97a08af9405733bfab79d516a00f4d0cf46cc34e" Oct 12 18:59:56 volumio go-librespot[2703]: time="2024-10-12T18:59:56+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 18:59:56 volumio go-librespot[2703]: time="2024-10-12T18:59:56+02:00" level=debug msg="obtained new client token: AAB7gs0kfOL/WqcM8/t9HSQ6Wkn5fD1E5oTT8BSRlBDXYoeBnV2LUkNwMm9t+7m8nxjgKv1KUp3ZRRxmHkN7KOf3U9DY2RudZDBWOVxi4IheyMKSi2l/4JAR7PvZUIzo8+VDfUgObuDlKuwnOLB9ynKatV3/z/MZxiONghEx3rNJq1StWewSopemUuQyBOJ+eh6dsc1Gut76nHzkodDJnKRIzlovDNbQhQ1mH1GoQE/25fT8KF6W5hkht5mRyg==" Oct 12 18:59:56 volumio go-librespot[2703]: time="2024-10-12T18:59:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 18:59:56 volumio go-librespot[2703]: time="2024-10-12T18:59:56+02:00" level=debug msg="completed keyexchange" Oct 12 18:59:57 volumio go-librespot[2703]: time="2024-10-12T18:59:57+02:00" level=debug msg="completed challenge" Oct 12 18:59:57 volumio go-librespot[2703]: time="2024-10-12T18:59:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 18:59:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 18:59:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 18:59:57 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 18:59:57 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:00 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:00 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Oct 12 19:00:00 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:00 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:00 volumio go-librespot[2711]: Librespot-go daemon starting... Oct 12 19:00:00 volumio go-librespot[2711]: time="2024-10-12T19:00:00+02:00" level=info msg="generated new device id: 1161b626ccd679fbfa30920e018f46c1d9c995ce" Oct 12 19:00:00 volumio go-librespot[2711]: time="2024-10-12T19:00:00+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:00 volumio go-librespot[2711]: time="2024-10-12T19:00:00+02:00" level=debug msg="obtained new client token: AABPRU6+KY02U+8MaxxKzbtsdDPKumspC/ZVmMVOASARnpITjp5DGqJMesThVKUyErrtW3CEpkBIF7c4WSnlHaba6LhVghZhMch8FnL2fs9xZfacxpYBupGLtfN2iKmm/h8Dal7v4ShrcruQqH3XzFdVxNdldRGPdJpBI98zlbFliWGyqLVmvvFL30G5LVB0qMcYunP6i6470Qz/RdWhfVe8f+PZCesDTO6EE8SYcRV51l8DCxS16WNiPXdMzw==" Oct 12 19:00:00 volumio go-librespot[2711]: time="2024-10-12T19:00:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:00 volumio go-librespot[2711]: time="2024-10-12T19:00:00+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:01 volumio go-librespot[2711]: time="2024-10-12T19:00:01+02:00" level=debug msg="completed challenge" Oct 12 19:00:01 volumio go-librespot[2711]: time="2024-10-12T19:00:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:03 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:03 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Oct 12 19:00:04 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:04 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:04 volumio go-librespot[2763]: Librespot-go daemon starting... Oct 12 19:00:04 volumio go-librespot[2763]: time="2024-10-12T19:00:04+02:00" level=info msg="generated new device id: d16cb0caf252dc7f692f94e83e232cb11c3f3f6d" Oct 12 19:00:04 volumio go-librespot[2763]: time="2024-10-12T19:00:04+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:04 volumio go-librespot[2763]: time="2024-10-12T19:00:04+02:00" level=debug msg="obtained new client token: AAChPSifazSRPBOYKmKPWnVGxgT9TIJyJWrQQeSeGJHdVkXfcnci7wtfDpmNmnUSVYUg8iOzFWO+yUeC2Q9PLmDxIXxMy41eNtxEqko8tKzf6JHCbdYcx7q4VIK6WMZrEu/WisZX9Zm8ZKRDuNxZ3Ld/izEElw3WHIvwUlJx+ksYrLUG34Bn8PO7G0H44qcZJCuM09IESc8bn3ZdwI29fyvhmPDAuC7Ji9O7R+qOGqtsOZu8UejE011GBFU21A==" Oct 12 19:00:04 volumio go-librespot[2763]: time="2024-10-12T19:00:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:04 volumio go-librespot[2763]: time="2024-10-12T19:00:04+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:05 volumio go-librespot[2763]: time="2024-10-12T19:00:05+02:00" level=debug msg="completed challenge" Oct 12 19:00:05 volumio go-librespot[2763]: time="2024-10-12T19:00:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:06 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:06 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Oct 12 19:00:08 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:08 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:08 volumio go-librespot[2773]: Librespot-go daemon starting... Oct 12 19:00:08 volumio go-librespot[2773]: time="2024-10-12T19:00:08+02:00" level=info msg="generated new device id: 2e675e5dbe51778a7dbe78583fd2cd59794f28c8" Oct 12 19:00:08 volumio go-librespot[2773]: time="2024-10-12T19:00:08+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:08 volumio go-librespot[2773]: time="2024-10-12T19:00:08+02:00" level=debug msg="obtained new client token: AAAMk1nhpjtWetSEGZ/WidjXj1XlsKEDjsNlq6UkKl8/cmZhMCtKAh7kuKjcJZ6z6E6NWwQEaczQol+/TA/e6kIDVfLVw2eHaIJqqBnho115i5b/c7L2gVr6+2O8ebdAj/9Mx7f9D/w4i1KnH92InKFR1TAwxL9bEwGRq6HqF6yfMx740I/CfQ8CTrOh22SRg+s7OcNurGDFLo0pAreV/JCrvHJlZiz26wWgUO7unrrjUlK0J4o/vc43ZBgpKg==" Oct 12 19:00:08 volumio go-librespot[2773]: time="2024-10-12T19:00:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 12 19:00:08 volumio go-librespot[2773]: time="2024-10-12T19:00:08+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:09 volumio go-librespot[2773]: time="2024-10-12T19:00:09+02:00" level=debug msg="completed challenge" Oct 12 19:00:09 volumio go-librespot[2773]: time="2024-10-12T19:00:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:09 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:09 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:12 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:12 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Oct 12 19:00:12 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:12 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:12 volumio go-librespot[2781]: Librespot-go daemon starting... Oct 12 19:00:12 volumio go-librespot[2781]: time="2024-10-12T19:00:12+02:00" level=info msg="generated new device id: 0d9487448ae6227f7c770acee7632a55e9beb655" Oct 12 19:00:12 volumio go-librespot[2781]: time="2024-10-12T19:00:12+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:12 volumio go-librespot[2781]: time="2024-10-12T19:00:12+02:00" level=debug msg="obtained new client token: AAC/UBSqHGTmIpdbtJh65RBGiT3QiacHzCXYC5vkZtZFxpv2FvW1gmReUcvi3ij1ZbYGzFIW806i3OI1CJAtlqvVPRGUla2BTFnSrg1D182Rqigjf+PNVwCjVpGz6E5TR3RJ1PLiiRVgs/f8pH1j8mnmE6RJMb9AmA87sRpN2t8DVyuj4t4ijE1acgp/FJA4PxywFgPxT6LeDMpToUkUD2b2XhWbijJc9A/ozh55WdV7O/1ErOGHY8BHLtL8Hg==" Oct 12 19:00:12 volumio go-librespot[2781]: time="2024-10-12T19:00:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 12 19:00:12 volumio go-librespot[2781]: time="2024-10-12T19:00:12+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:13 volumio go-librespot[2781]: time="2024-10-12T19:00:13+02:00" level=debug msg="completed challenge" Oct 12 19:00:13 volumio go-librespot[2781]: time="2024-10-12T19:00:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:15 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:15 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Oct 12 19:00:16 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:16 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:16 volumio go-librespot[2789]: Librespot-go daemon starting... Oct 12 19:00:16 volumio go-librespot[2789]: time="2024-10-12T19:00:16+02:00" level=info msg="generated new device id: d3c8e8caf68470b7a11b06d9653ba962f2580e52" Oct 12 19:00:16 volumio go-librespot[2789]: time="2024-10-12T19:00:16+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:16 volumio go-librespot[2789]: time="2024-10-12T19:00:16+02:00" level=debug msg="obtained new client token: AAAtyJmg0d9M/hyXyOsgUtVDGkQP3TFoQxchv6riP3Cbw8Z/eNJCJBlzHOXRQx/rwM+YVMLayuPxlam42B/YqlkuQsinLIz2SSvD7eosNhHURb0be2GeYmlWrgpiFR/E4mdGHNNUIjNUnpgiMKCMOUNHyGQ61M4UcgbRc0yHpX8mb5ZSK55TGi1rCMbYV4AFJTM8ZJrl/F5mLRWW1csFk8wwtBX2tD9gThm+WEEQC3a40NcLdmq1jO6ksbPv5Q==" Oct 12 19:00:16 volumio go-librespot[2789]: time="2024-10-12T19:00:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:16 volumio go-librespot[2789]: time="2024-10-12T19:00:16+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:17 volumio go-librespot[2789]: time="2024-10-12T19:00:17+02:00" level=debug msg="completed challenge" Oct 12 19:00:17 volumio go-librespot[2789]: time="2024-10-12T19:00:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:18 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:18 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Oct 12 19:00:20 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:20 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:20 volumio go-librespot[2797]: Librespot-go daemon starting... Oct 12 19:00:20 volumio go-librespot[2797]: time="2024-10-12T19:00:20+02:00" level=info msg="generated new device id: 9db1e9d206ac6449fe4cb142c43adcd60dd67280" Oct 12 19:00:20 volumio go-librespot[2797]: time="2024-10-12T19:00:20+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:20 volumio go-librespot[2797]: time="2024-10-12T19:00:20+02:00" level=debug msg="obtained new client token: AADA41eLBQswwqee/tKy4+6aCxQw4x3kSCxXYuCKh+/E/VJHzQGZzp/xzEqYOmVXG9m6Cl0CQY8jR5qDrQ8XGtD8VWWAx6j0lQ4FOy47UiBmpssghIWDNVE2xEygd9m5omF+m+u2ChP6POO+E+De3lSowUNGeEaOKzUXqqzwPtPEpcUopC3aLrDT15AbxYnw20WAllYWoFUVX24XfUVfTtd960AW7tnQblc6st97X1GTkPib48xPce+LOL5H4A==" Oct 12 19:00:20 volumio go-librespot[2797]: time="2024-10-12T19:00:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 12 19:00:20 volumio go-librespot[2797]: time="2024-10-12T19:00:20+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:21 volumio go-librespot[2797]: time="2024-10-12T19:00:21+02:00" level=debug msg="completed challenge" Oct 12 19:00:21 volumio go-librespot[2797]: time="2024-10-12T19:00:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:21 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:21 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:24 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:24 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Oct 12 19:00:24 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:24 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:24 volumio go-librespot[2805]: Librespot-go daemon starting... Oct 12 19:00:24 volumio go-librespot[2805]: time="2024-10-12T19:00:24+02:00" level=info msg="generated new device id: 4ebb6bad9b8e13da62612306236a0d713b755993" Oct 12 19:00:24 volumio go-librespot[2805]: time="2024-10-12T19:00:24+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:24 volumio go-librespot[2805]: time="2024-10-12T19:00:24+02:00" level=debug msg="obtained new client token: AAAxP3HgCXS2h6D/ycqCh7ZhuPwPLm+mL6njyhJUjGfd89Yuc4VW1oOXVz0U0FZ+fdVyVKZJwe7tjVU9/5y1JjF7H6JGbTb5JqYr56M7HcRzIC8PtzZawRtK/m6Lvr1szH/AuZLizktxFaJ28lxwYxK+xTHtx9daMFlKYLnObBvixId8n94ZVN3GGLM4uPfruQHPZy20Wd/cmLgefEE2u/I2gm5AfX3T0CyNOiNCwcPJ5jtqVI8y6ylXadgNZw==" Oct 12 19:00:24 volumio go-librespot[2805]: time="2024-10-12T19:00:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:24 volumio go-librespot[2805]: time="2024-10-12T19:00:24+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:25 volumio go-librespot[2805]: time="2024-10-12T19:00:25+02:00" level=debug msg="completed challenge" Oct 12 19:00:25 volumio go-librespot[2805]: time="2024-10-12T19:00:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:27 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:27 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Oct 12 19:00:28 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:28 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:28 volumio go-librespot[2814]: Librespot-go daemon starting... Oct 12 19:00:28 volumio go-librespot[2814]: time="2024-10-12T19:00:28+02:00" level=info msg="generated new device id: 139d51b8ba916ca2b28865084749e49bc53dc03c" Oct 12 19:00:28 volumio go-librespot[2814]: time="2024-10-12T19:00:28+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:28 volumio go-librespot[2814]: time="2024-10-12T19:00:28+02:00" level=debug msg="obtained new client token: AAD33CzQcEgip20qSsBptm0v36bPuqWU4bThvssq/GjzEFjCjVT4bmfuOIz80KH94BCsZyalE1QZ8Rbz1foj3NaQtB4DwT/+itTb51z6TGQDAZEaJG1BSGew1K0D/lcl3YvRCW9KHP4gxB/H7XdUGKs92qD60Jwr0yvV50E1UuoQgKv411ApyPXwzHGESurJ6Sf+ADEYrHwGnvM51jr9P6+gXnZ9gPtU6s5BI15it4NBXqlEmIamoq1pgFHFyg==" Oct 12 19:00:28 volumio go-librespot[2814]: time="2024-10-12T19:00:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:28 volumio go-librespot[2814]: time="2024-10-12T19:00:28+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:29 volumio go-librespot[2814]: time="2024-10-12T19:00:29+02:00" level=debug msg="completed challenge" Oct 12 19:00:29 volumio go-librespot[2814]: time="2024-10-12T19:00:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:30 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:30 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Oct 12 19:00:32 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:32 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:32 volumio go-librespot[2823]: Librespot-go daemon starting... Oct 12 19:00:32 volumio go-librespot[2823]: time="2024-10-12T19:00:32+02:00" level=info msg="generated new device id: cff3d512fbeb2863622ba8ddfe5ac8b7f1178b1b" Oct 12 19:00:32 volumio go-librespot[2823]: time="2024-10-12T19:00:32+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:32 volumio go-librespot[2823]: time="2024-10-12T19:00:32+02:00" level=debug msg="obtained new client token: AABXsBj9/DxokKnxAanl+3n6o3HdSVgey9jmEGUHLMPpEPOL1l3Ski21CAYSLQfWy9DnvyqHFZpBByjgKd6nWWgz+wdPZ/pe+kNsquWCnKiQuA3K7399uUcNKa1UyYfJHr2jJ8tDHmUjUNHHdaHDSb0icXgDiRh6djtDSIYpF5qYUgGKsHhWg5sIcHivvgkW5iB7WlqmXcwZ923o/coWI66sCtOHIy8sUbcKFhJuEw5s14tpz2ktA7nRiCQnzw==" Oct 12 19:00:32 volumio go-librespot[2823]: time="2024-10-12T19:00:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:32 volumio go-librespot[2823]: time="2024-10-12T19:00:32+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:33 volumio go-librespot[2823]: time="2024-10-12T19:00:33+02:00" level=debug msg="completed challenge" Oct 12 19:00:33 volumio go-librespot[2823]: time="2024-10-12T19:00:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:33 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:33 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:36 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:36 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Oct 12 19:00:36 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:36 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:36 volumio go-librespot[2831]: Librespot-go daemon starting... Oct 12 19:00:36 volumio go-librespot[2831]: time="2024-10-12T19:00:36+02:00" level=info msg="generated new device id: 1f1eda9322705a84b1ad527ac130388cb0ad3fa8" Oct 12 19:00:36 volumio go-librespot[2831]: time="2024-10-12T19:00:36+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:36 volumio go-librespot[2831]: time="2024-10-12T19:00:36+02:00" level=debug msg="obtained new client token: AAB51G94RIvqH8ycF3Yk8mSKvnrbF7Ep0QO++MSykTs6emYf7f+sn8HgcOoX+QH+MRn+8K61ernLNeChNcxIyCovhSVPDQ89TA3Rvz0q5k8qq+dODJwpMekpSsUtcQ37Rqn3ubjpwga/U/O1+C2V+LRbf6h/R5MHADGbQxAEhvYZVEyA4S/bxbHrnXDglEsZNw08zIBzIlfgMhMNRNeSRYsQIuNRPEj8OryLKL5RtXdFZWW9KxzkULDo4OWfJA==" Oct 12 19:00:36 volumio go-librespot[2831]: time="2024-10-12T19:00:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:36 volumio go-librespot[2831]: time="2024-10-12T19:00:36+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:37 volumio go-librespot[2831]: time="2024-10-12T19:00:37+02:00" level=debug msg="completed challenge" Oct 12 19:00:37 volumio go-librespot[2831]: time="2024-10-12T19:00:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:39 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:39 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Oct 12 19:00:40 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:40 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:40 volumio go-librespot[2840]: Librespot-go daemon starting... Oct 12 19:00:40 volumio go-librespot[2840]: time="2024-10-12T19:00:40+02:00" level=info msg="generated new device id: d99894c77243784af0a28e4760d90235ad0084fc" Oct 12 19:00:40 volumio go-librespot[2840]: time="2024-10-12T19:00:40+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:40 volumio go-librespot[2840]: time="2024-10-12T19:00:40+02:00" level=debug msg="obtained new client token: AABTzW7tfMJP2uexYbbvhapAvsmiz589//mliVXADYjO9vIdlSm539J4kQLRXXbU485/CTP8Rdkn8kAo/KYyrj0Rjxh+pwNbPKk1a8GVJLOYMfq+LTR9qV9wXV+Sqx0+okbP7QlgHGbNLZ4QXSnweZZekHCNfqajRRgWep6oolpPC8XR1jlthOv1o0D82RDcpclyMLR2+tHvL20cH8MEWf0XhX1ZDXl57kw/mYqtWxKn+xzZffkXJhzBp+b4Ag==" Oct 12 19:00:40 volumio go-librespot[2840]: time="2024-10-12T19:00:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:40 volumio go-librespot[2840]: time="2024-10-12T19:00:40+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:41 volumio go-librespot[2840]: time="2024-10-12T19:00:41+02:00" level=debug msg="completed challenge" Oct 12 19:00:41 volumio go-librespot[2840]: time="2024-10-12T19:00:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:42 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:42 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Oct 12 19:00:44 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:44 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:44 volumio go-librespot[2848]: Librespot-go daemon starting... Oct 12 19:00:44 volumio go-librespot[2848]: time="2024-10-12T19:00:44+02:00" level=info msg="generated new device id: 3aad7e7092850f219852b62f4623263cdf96c5cc" Oct 12 19:00:44 volumio go-librespot[2848]: time="2024-10-12T19:00:44+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:44 volumio go-librespot[2848]: time="2024-10-12T19:00:44+02:00" level=debug msg="obtained new client token: AADeOryh+MoPJTRsV209DsFmNq8GLX4xFPFV8OC9AaGulBzXmv8VCW6JDKm9W/BpFy2BMRW5sxq/hFuvZF3czbAK7Mce42qCcGiuyGLl8DASNKpkwjGorqEWcGQ2ctXz0mnKU8p2d0iXSIUn7MSGzCJwjmP3mlToi+XNv0Nc+AQ4LlJZz/fAvSLT5+zIfa8+dSx2tbZ338KyllqpOIpdbT+N1MqfuThurAUeyQjdWJuTkAe8tzonsYDWFxnEGA==" Oct 12 19:00:44 volumio go-librespot[2848]: time="2024-10-12T19:00:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:44 volumio go-librespot[2848]: time="2024-10-12T19:00:44+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:45 volumio go-librespot[2848]: time="2024-10-12T19:00:45+02:00" level=debug msg="completed challenge" Oct 12 19:00:45 volumio go-librespot[2848]: time="2024-10-12T19:00:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:45 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:45 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 19:00:46 volumio volumio[965]: info: Discovery: Getting this device information Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:46 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 19:00:46 volumio volumio[965]: verbose: New Socket.io Connection to 192.168.1.43:3000 from 192.168.1.20 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:46 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 19:00:46 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 19:00:48 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:48 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Oct 12 19:00:48 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:48 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:48 volumio go-librespot[2857]: Librespot-go daemon starting... Oct 12 19:00:48 volumio go-librespot[2857]: time="2024-10-12T19:00:48+02:00" level=info msg="generated new device id: 62b2040fe8055452b53e0a92c820ad16c98a76f8" Oct 12 19:00:48 volumio go-librespot[2857]: time="2024-10-12T19:00:48+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:48 volumio go-librespot[2857]: time="2024-10-12T19:00:48+02:00" level=debug msg="obtained new client token: AADZZhYoUeFxDtMxZV5I3PaPLXQdwF1ZwKfDX3Fna26iu4LtpZp9+JF1LPQ5gTZpOuqqXZb5ohGc5EPlQd7CXEVbsPB+BE9gSdcK+9Qc222toa7KUIagc1kwmazhGtxvSJUqkS+TjwaG65hPSB1r4fqJm3NNC/bRLm2EDq4gBC1zX1o/R9muG4VpA0NLufY0+8YfypGbCKjlr+iC3Sxlfovs024EV4sIKlvgCW/tx8aO+co28mMcFAeSCaZSJA==" Oct 12 19:00:48 volumio go-librespot[2857]: time="2024-10-12T19:00:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 12 19:00:48 volumio go-librespot[2857]: time="2024-10-12T19:00:48+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:49 volumio go-librespot[2857]: time="2024-10-12T19:00:49+02:00" level=debug msg="completed challenge" Oct 12 19:00:49 volumio go-librespot[2857]: time="2024-10-12T19:00:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:49 volumio sudo[2866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 19:00:49 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 19:00:49 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Oct 12 19:00:49 volumio sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 19:00:49 volumio sudo[2869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 19:00:49 volumio sudo[2869]: pam_unix(sudo:session): session closed for user root Oct 12 19:00:49 volumio volumio[965]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 19:00:50 volumio sudo[2874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 19:00:50 volumio sudo[2874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 19:00:50 volumio sudo[2874]: pam_unix(sudo:session): session closed for user root Oct 12 19:00:50 volumio sudo[2877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 19:00:50 volumio sudo[2877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 19:00:50 volumio sudo[2877]: pam_unix(sudo:session): session closed for user root Oct 12 19:00:50 volumio volumio[965]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:50 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 19:00:50 volumio volumio[965]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 19:00:50 volumio volumio[965]: info: Received Get System Info Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 19:00:50 volumio volumio[965]: info: Discovery: Getting this device information Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:50 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:50 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:50 volumio volumio[965]: info: Listing playlists Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 19:00:50 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 19:00:51 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:51 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:51 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 19:00:51 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 19:00:51 volumio volumio[965]: info: Discovery: Getting this device information Oct 12 19:00:51 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:51 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:51 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 19:00:52 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 19:00:52 volumio volumio[965]: info: Received Get System Info Oct 12 19:00:52 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 19:00:52 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 19:00:52 volumio volumio[965]: info: Discovery: Getting this device information Oct 12 19:00:52 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:52 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:52 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 19:00:52 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 19:00:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Oct 12 19:00:52 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:52 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:52 volumio go-librespot[2881]: Librespot-go daemon starting... Oct 12 19:00:52 volumio go-librespot[2881]: time="2024-10-12T19:00:52+02:00" level=info msg="generated new device id: 0b04b9173e064d796695e3427dde117c51a164f1" Oct 12 19:00:52 volumio go-librespot[2881]: time="2024-10-12T19:00:52+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:52 volumio go-librespot[2881]: time="2024-10-12T19:00:52+02:00" level=debug msg="obtained new client token: AAATh/v8QjeGh1qdXYD8RD7+yfCOZ54BsARbouOK/XDqM56rhmF5bsX3AXyLDvn1fxzmJHnKrMVbglM2dRpTMNWCo7GyRzaS7yZYNck8jkWhfE7bw2tMeijoO1tirJPUzWWPBxdxVzVF9S+OY+4zor3KKkdPTu7hM6FIliZllwFa149aMUcODrQqFgawrqWRVDgxPLpZ7TdY+NUPlLOjglVX4JP07USe1y+fum1cjdcb2lK9xhC3rvTFA+Jj9w==" Oct 12 19:00:52 volumio go-librespot[2881]: time="2024-10-12T19:00:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:52 volumio go-librespot[2881]: time="2024-10-12T19:00:52+02:00" level=debug msg="completed keyexchange" Oct 12 19:00:53 volumio go-librespot[2881]: time="2024-10-12T19:00:53+02:00" level=debug msg="completed challenge" Oct 12 19:00:53 volumio go-librespot[2881]: time="2024-10-12T19:00:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 12 19:00:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 19:00:53 volumio volumio[965]: info: Received Get System Info Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 19:00:53 volumio volumio[965]: info: Discovery: Getting this device information Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Oct 12 19:00:53 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 19:00:53 volumio volumio[965]: info: Retrieving Cloud Streaming UI Oct 12 19:00:53 volumio volumio[965]: info: Getting Tidal Cloud Configuration Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 19:00:53 volumio volumio[965]: info: Getting Qobuz Cloud Configuration Oct 12 19:00:53 volumio volumio[965]: info: Asking plugin for UI Config Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 19:00:53 volumio volumio[965]: info: Getting Spotify Cloud Configuration Oct 12 19:00:53 volumio volumio[965]: info: Asking plugin for UI Config Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 19:00:53 volumio volumio[965]: info: Saving Spotify Acccount Oct 12 19:00:53 volumio volumio[965]: info: Got it Oct 12 19:00:53 volumio volumio[965]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 12 19:00:53 volumio volumio[965]: info: Got Tidal Cloud Configuration Oct 12 19:00:53 volumio volumio[965]: info: Got it Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 19:00:53 volumio volumio[965]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 19:00:54 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:54 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:54 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 19:00:54 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 12 19:00:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 12 19:00:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Oct 12 19:00:56 volumio systemd[1]: Stopped go-librespot Daemon. Oct 12 19:00:56 volumio systemd[1]: Started go-librespot Daemon. Oct 12 19:00:56 volumio go-librespot[2889]: Librespot-go daemon starting... Oct 12 19:00:56 volumio go-librespot[2889]: time="2024-10-12T19:00:56+02:00" level=info msg="generated new device id: e85307826b0c556f5de96212558fb8b487a1f1a6" Oct 12 19:00:56 volumio go-librespot[2889]: time="2024-10-12T19:00:56+02:00" level=debug msg="stored credentials found for sloam8" Oct 12 19:00:56 volumio go-librespot[2889]: time="2024-10-12T19:00:56+02:00" level=debug msg="obtained new client token: AADOWqBAtKJZtQYb8h4DDMcP4owNPhYu9gaRqjBt75q3RgjYHqtHQv7qbAE3rwoP7U4JmSlwR5V6TcT87zkizvndFbMfxcZe2jcXK8dxqmoq3J2ti0XEJhGAUwzMe8KLRPPI/AJ1wRFjjmAD7wFIT4gkCcIhZiErCIN9s4jCRpyrpzsulH4F3SvBRmkW5vI/kp3IX9X/zBvLMGwvYzxSr199NzETvNGB9DuRKLTfZOcxmghQ7oToLWgxlI95ug==" Oct 12 19:00:56 volumio go-librespot[2889]: time="2024-10-12T19:00:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:00:56 volumio go-librespot[2889]: time="2024-10-12T19:00:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.9:4070: connect: connection refused" Oct 12 19:00:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:00:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 12 19:00:56 volumio volumio[965]: info: Disabling MyMusic plugin upnp Oct 12 19:00:56 volumio sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 12 19:00:56 volumio sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 19:00:56 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 12 19:00:56 volumio volumio[965]: error: Upnp client error: Error: This socket has been ended by the other party Oct 12 19:00:56 volumio volumio[965]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 12 19:00:57 volumio volumio[965]: info: Initializing connection to go-librespot Websocket Oct 12 19:00:57 volumio volumio[965]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 12 19:00:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 19:00:59 volumio volumio[965]: info: Enabling MyMusic plugin upnp Oct 12 19:00:59 volumio volumio[965]: info: Enabling plugin upnp Oct 12 19:00:59 volumio volumio[965]: info: Loading plugin "upnp"... Oct 12 19:00:59 volumio volumio[965]: info: [1728752459472] Starting Upmpd Daemon Oct 12 19:00:59 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 19:00:59 volumio volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 19:00:59 volumio volumio[965]: Error: listen EADDRINUSE: address already in use :::6599 Oct 12 19:00:59 volumio volumio[965]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 12 19:00:59 volumio volumio[965]: at listenInCluster (net.js:1379:12) Oct 12 19:00:59 volumio volumio[965]: at Server.listen (net.js:1465:7) Oct 12 19:00:59 volumio volumio[965]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 12 19:00:59 volumio volumio[965]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 12 19:00:59 volumio volumio[965]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 12 19:00:59 volumio volumio[965]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 12 19:00:59 volumio volumio[965]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 12 19:00:59 volumio volumio[965]: code: 'EADDRINUSE', Oct 12 19:00:59 volumio volumio[965]: errno: -98, Oct 12 19:00:59 volumio volumio[965]: syscall: 'listen', Oct 12 19:00:59 volumio volumio[965]: address: '::', Oct 12 19:00:59 volumio volumio[965]: port: 6599 Oct 12 19:00:59 volumio volumio[965]: } Oct 12 19:00:59 volumio volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 19:00:59 volumio sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 18:59 Oct 12 19:00:59 volumio sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"