-- Logs begin at Thu 2025-06-12 00:21:55 UTC, end at Thu 2025-06-12 03:14:53 UTC. -- Jun 12 03:13:01 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:01 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233275. Jun 12 03:13:01 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:01 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:01 volumio go-librespot[3249]: Librespot-go daemon starting... Jun 12 03:13:01 volumio go-librespot[3249]: time="2025-06-12T03:13:01Z" level=info msg="generated new device id: d18a70d1a254070d1c4814e3cb6c3ff70deb9856" Jun 12 03:13:01 volumio go-librespot[3249]: time="2025-06-12T03:13:01Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:01 volumio go-librespot[3249]: time="2025-06-12T03:13:01Z" level=debug msg="obtained new client token: AACtRp1aMLtOGLXOYH9InFU5sobB9F5199LVf+UWVtnXy4NOyOK3LtIEcTQLFqvUUpEBINHLTpIVUwY15dobxeGO8IqtTlT8gq9UZvnHlL1LNQnCnV2uIBeeCVoHOFWJTU8ao2scpQEii1jfystg67bhm9wMNbfZHkVddQ0Ii/Gu5uPTvP0xzvJJs1z9BNdiKLpbGwRCAaFY/J0tfbaG+zcQr7KQFVndNPO6xX+y4eYHpZk+ueFoCekIR9e6tTE=" Jun 12 03:13:01 volumio go-librespot[3249]: time="2025-06-12T03:13:01Z" 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]" Jun 12 03:13:01 volumio go-librespot[3249]: time="2025-06-12T03:13:01Z" level=debug msg="completed keyexchange" Jun 12 03:13:02 volumio go-librespot[3249]: time="2025-06-12T03:13:02Z" level=debug msg="completed challenge" Jun 12 03:13:02 volumio go-librespot[3249]: time="2025-06-12T03:13:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:04 volumio volumio-remote-updater[673]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m" Jun 12 03:13:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:13:04 volumio volumio[877]: updateProgress Jun 12 03:13:04 volumio volumio[877]: { Jun 12 03:13:04 volumio volumio[877]: downloadSpeed: '', Jun 12 03:13:04 volumio volumio[877]: eta: '4m', Jun 12 03:13:04 volumio volumio[877]: progress: 30, Jun 12 03:13:04 volumio volumio[877]: status: 'Downloading new update' Jun 12 03:13:04 volumio volumio[877]: } Jun 12 03:13:04 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:04 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233276. Jun 12 03:13:05 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:05 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:05 volumio go-librespot[3276]: Librespot-go daemon starting... Jun 12 03:13:05 volumio go-librespot[3276]: time="2025-06-12T03:13:05Z" level=info msg="generated new device id: 2a05a65be16b0060848ef26ee7bcd2695ba140da" Jun 12 03:13:05 volumio go-librespot[3276]: time="2025-06-12T03:13:05Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:05 volumio go-librespot[3276]: time="2025-06-12T03:13:05Z" level=debug msg="obtained new client token: AACgmiyaczStSOZBRILDoF6nt3jJrulxHgBUYLkLyyOgU4cBaw+iaRyyWAsOWL1XYAOEGUfhXQwphoT+i8j696cYTR6jeeFM9UJmXjB5PXunSQ1WIp6dnM5XvQDQoMDSGPKLG3au+X/Fe5rGsGoxE4j2c1qBuUEM3DBBgDN0cJGjfqeZD6H/NdiB9ZYvMFOreK+npgNvnOuE3e7Hb0R6SEKBQ6qYivZHyJDO6f5uRnyeD3hUzEddwb4weBVf6rE=" Jun 12 03:13:05 volumio go-librespot[3276]: time="2025-06-12T03:13:05Z" 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]" Jun 12 03:13:05 volumio go-librespot[3276]: time="2025-06-12T03:13:05Z" level=debug msg="completed keyexchange" Jun 12 03:13:06 volumio go-librespot[3276]: time="2025-06-12T03:13:06Z" level=debug msg="completed challenge" Jun 12 03:13:06 volumio go-librespot[3276]: time="2025-06-12T03:13:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:07 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:07 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233277. Jun 12 03:13:09 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:09 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:09 volumio go-librespot[3334]: Librespot-go daemon starting... Jun 12 03:13:09 volumio go-librespot[3334]: time="2025-06-12T03:13:09Z" level=info msg="generated new device id: 2dc06e9013d6214c2d26e45dae3558dece71bd9a" Jun 12 03:13:09 volumio go-librespot[3334]: time="2025-06-12T03:13:09Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:09 volumio go-librespot[3334]: time="2025-06-12T03:13:09Z" level=debug msg="obtained new client token: AAABD46d8+xTizhzJCLQD6DcprJ5zk3jxkcziYE/0VWKZzwX5jsDwS+EGMO4vGQhM3t4ZZFGF3knNxckYJK6u95ESyBcdOWiB2AakUUOv9DbLSVJhRUWFVcN7q1VM43jvaxGAOH2VkQIGoeXu4exQ7CgyEQnD/IgR7T3dLMQxS2RDOFPqRbfkc1vKmU++wIWtba5nPmZF7Sr106NxRQ5ZnfcVBIlYaYewAiGj8Cb/zWNOtzh1ziTeOH39P8ec7s=" Jun 12 03:13:09 volumio go-librespot[3334]: time="2025-06-12T03:13:09Z" 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]" Jun 12 03:13:09 volumio go-librespot[3334]: time="2025-06-12T03:13:09Z" level=debug msg="completed keyexchange" Jun 12 03:13:10 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:10 volumio go-librespot[3334]: time="2025-06-12T03:13:10Z" level=debug msg="new websocket client" Jun 12 03:13:10 volumio volumio[877]: info: Connection to go-librespot Websocket established Jun 12 03:13:10 volumio go-librespot[3334]: time="2025-06-12T03:13:10Z" level=debug msg="completed challenge" Jun 12 03:13:10 volumio go-librespot[3334]: time="2025-06-12T03:13:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:10 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jun 12 03:13:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:13 volumio volumio[877]: info: Getting Spotify volume Jun 12 03:13:13 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:13 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 12 03:13:13 volumio volumio[877]: (node:877) 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: 20914) Jun 12 03:13:13 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 12 03:13:13 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jun 12 03:13:13 volumio volumio[877]: info: CorePlayQueue::getTrack 19 Jun 12 03:13:13 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 12 03:13:13 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:13 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233278. Jun 12 03:13:13 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:13 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:13 volumio go-librespot[3380]: Librespot-go daemon starting... Jun 12 03:13:13 volumio go-librespot[3380]: time="2025-06-12T03:13:13Z" level=info msg="generated new device id: d77cae9889667b05f82b13c785a9995eb2f5cba1" Jun 12 03:13:13 volumio go-librespot[3380]: time="2025-06-12T03:13:13Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:13 volumio go-librespot[3380]: time="2025-06-12T03:13:13Z" level=debug msg="obtained new client token: AAD+xxEjQ/F648Jjsbr9m0O7sUoM0iILzCMWkr+wAbaS77PsdGYvpBsgnc+xpumoB62Q+3a1WtSXuFvjZU1HAK8CgCPCg7nLAT8b2Nzbvbl9hPlcypYaFT+7dryZQxUaLgpFkKGS8y7aeqytNgrw+VRKyeORpnZiGAAHZilsB692yOaM8mY5RAmZ4qDJOHd+SriGzpW1DR/GZH/jNE1bfglCigOTAFdjCfAqKEL37GD9nvv/rBTo72tzdDkJFVE=" Jun 12 03:13:13 volumio go-librespot[3380]: time="2025-06-12T03:13:13Z" 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]" Jun 12 03:13:13 volumio go-librespot[3380]: time="2025-06-12T03:13:13Z" level=debug msg="completed keyexchange" Jun 12 03:13:14 volumio go-librespot[3380]: time="2025-06-12T03:13:14Z" level=debug msg="completed challenge" Jun 12 03:13:14 volumio go-librespot[3380]: time="2025-06-12T03:13:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:16 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:16 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233279. Jun 12 03:13:17 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:17 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:17 volumio go-librespot[3402]: Librespot-go daemon starting... Jun 12 03:13:17 volumio go-librespot[3402]: time="2025-06-12T03:13:17Z" level=info msg="generated new device id: 4df480846b7ecf520a5b4fff7945b420dfc660b0" Jun 12 03:13:17 volumio go-librespot[3402]: time="2025-06-12T03:13:17Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:17 volumio go-librespot[3402]: time="2025-06-12T03:13:17Z" level=debug msg="obtained new client token: AAABa119+fLE7OyjwbQq+pmX9+BJQrWrHc1NBi6tSvQNoL0QUJl2Vf57bCn8Od6QIeIp9tb7xEGHLOjeZJgvbpkIN/TG1dhN4sVHrMYT8gaGjWBz+oq0a3O19M44FWqvyPIOHf2QRaBjtq+qyd0ai9Udki/f5q/RtXv7dNnsK2D1KXbvn0qvqjwyWqTPkxOkoUck4iVcIfsoNhaUfYkvcJSi1q2NxTs3LyKwA+NYUlzXVSRyyuZ+TjWCUPQ8aN0=" Jun 12 03:13:17 volumio go-librespot[3402]: time="2025-06-12T03:13:17Z" 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]" Jun 12 03:13:17 volumio go-librespot[3402]: time="2025-06-12T03:13:17Z" level=debug msg="completed keyexchange" Jun 12 03:13:18 volumio go-librespot[3402]: time="2025-06-12T03:13:18Z" level=debug msg="completed challenge" Jun 12 03:13:18 volumio go-librespot[3402]: time="2025-06-12T03:13:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:19 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:19 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233280. Jun 12 03:13:21 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:21 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:21 volumio go-librespot[3410]: Librespot-go daemon starting... Jun 12 03:13:21 volumio go-librespot[3410]: time="2025-06-12T03:13:21Z" level=info msg="generated new device id: 50e06572cb5c9f9658212d245854979a81fc46c3" Jun 12 03:13:21 volumio go-librespot[3410]: time="2025-06-12T03:13:21Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:21 volumio go-librespot[3410]: time="2025-06-12T03:13:21Z" level=debug msg="obtained new client token: AAB46h5skji9Yd+NUG5bfaCcuMeLCpocho2kjf1mmuYLqhA7kKYnwpqilGm3lXnvjrxXfBrvwBVlV6Xnrs/TxA+kVKExRMVTZ9YpQSzdl1Fl8AY8Ub+lzy849/H7zV72pQrfcG2FqTZRg4Ca1NhSF1GrA02pMHQiJyRDycXZ1zaJZ8zPZbuOTvU5v7czXLN5iSxnkJJ0fDQdwZ4sezFg0doZOhJMp/CdqMLeLW9VqSQUIXCRO8U/XBWATn85ais=" Jun 12 03:13:22 volumio go-librespot[3410]: time="2025-06-12T03:13:22Z" 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]" Jun 12 03:13:22 volumio go-librespot[3410]: time="2025-06-12T03:13:22Z" level=debug msg="completed keyexchange" Jun 12 03:13:22 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:22 volumio go-librespot[3410]: time="2025-06-12T03:13:22Z" level=debug msg="new websocket client" Jun 12 03:13:22 volumio volumio[877]: info: Connection to go-librespot Websocket established Jun 12 03:13:22 volumio go-librespot[3410]: time="2025-06-12T03:13:22Z" level=debug msg="completed challenge" Jun 12 03:13:22 volumio go-librespot[3410]: time="2025-06-12T03:13:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:22 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jun 12 03:13:25 volumio volumio[877]: info: Getting Spotify volume Jun 12 03:13:25 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:25 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 12 03:13:25 volumio volumio[877]: (node:877) 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: 20915) Jun 12 03:13:25 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 12 03:13:25 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jun 12 03:13:25 volumio volumio[877]: info: CorePlayQueue::getTrack 19 Jun 12 03:13:25 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 12 03:13:25 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:25 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233281. Jun 12 03:13:25 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:25 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:25 volumio go-librespot[3421]: Librespot-go daemon starting... Jun 12 03:13:25 volumio go-librespot[3421]: time="2025-06-12T03:13:25Z" level=info msg="generated new device id: cafc3f55e488090e9f7b7a8df699a058f4ecbf77" Jun 12 03:13:25 volumio go-librespot[3421]: time="2025-06-12T03:13:25Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:25 volumio go-librespot[3421]: time="2025-06-12T03:13:25Z" level=debug msg="obtained new client token: AADl33EQ6Ia3DeZxKyXft8yzldWBPpAnhunNAW1tBhM8Q83EERku6h3ZTK6ojEOjQEmxHH93bmpHxhGIiYdHRndgqLAPT9cj9bSUX6Gv68hDsk6q7Os4VOqUi1nCVTNGrVwZyqyhZ7uO+f6/BJwaHSWpvmVb6S0GS4LYL8tSIAp3liueWZ7zvnBS3Wcorycr2JtVlw0DmfwNNarxPOfhNkMkhz4V8O3MapZmQuBKpR8JHxo0UgE/L8Zk9Gm8T4s=" Jun 12 03:13:25 volumio go-librespot[3421]: time="2025-06-12T03:13:25Z" 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]" Jun 12 03:13:25 volumio go-librespot[3421]: time="2025-06-12T03:13:25Z" level=debug msg="completed keyexchange" Jun 12 03:13:26 volumio go-librespot[3421]: time="2025-06-12T03:13:26Z" level=debug msg="completed challenge" Jun 12 03:13:26 volumio go-librespot[3421]: time="2025-06-12T03:13:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:28 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:28 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233282. Jun 12 03:13:29 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:29 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:29 volumio go-librespot[3444]: Librespot-go daemon starting... Jun 12 03:13:29 volumio go-librespot[3444]: time="2025-06-12T03:13:29Z" level=info msg="generated new device id: 48fd5c29bd1c34fc8e03b0f33999f2f386de3d7c" Jun 12 03:13:29 volumio go-librespot[3444]: time="2025-06-12T03:13:29Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:29 volumio go-librespot[3444]: time="2025-06-12T03:13:29Z" level=debug msg="obtained new client token: AAAI3g6I7kWcCTWfDTI1+jSeXXxxpYh5Q0Ph0IZEQMTFwn0xtKI7BhkzXMb4r+VyeSNgGrZ3Wrdr1cS2sVFqsWGKvxOU/361dV/0k2NuzshLO4KtPfN/3d3soIv9YYSIY7wP0uqHNtlHJrfM7C9nLOfnkKyhf88chaFBXUvDeCW8TVdiO0C7D0YedNfF4dct/Uuv+ocD9y/Eyp32WMTEikLwwQz/aShIR2j+2utLZWRVblExIv3VnnhhseuAlAw=" Jun 12 03:13:29 volumio go-librespot[3444]: time="2025-06-12T03:13:29Z" 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]" Jun 12 03:13:29 volumio go-librespot[3444]: time="2025-06-12T03:13:29Z" level=debug msg="completed keyexchange" Jun 12 03:13:30 volumio go-librespot[3444]: time="2025-06-12T03:13:30Z" level=debug msg="completed challenge" Jun 12 03:13:30 volumio go-librespot[3444]: time="2025-06-12T03:13:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:31 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:31 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233283. Jun 12 03:13:33 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:33 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:33 volumio go-librespot[3452]: Librespot-go daemon starting... Jun 12 03:13:34 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:34 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:35 volumio go-librespot[3452]: time="2025-06-12T03:13:35Z" level=info msg="generated new device id: 093d02a1ae96c1d97b0536974ee9104d432ee9b7" Jun 12 03:13:35 volumio go-librespot[3452]: time="2025-06-12T03:13:35Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:35 volumio go-librespot[3452]: time="2025-06-12T03:13:35Z" level=debug msg="obtained new client token: AACeTM1dvea0iooTnWi5kcXET+Lf/1IVo7WdVFQAOskw5VRITCQrRK18MiAV4XVpNthTo3FfiiduiLe+jR5PASg2+DgaPj5GIbkPgl288K4tlCbmAjtda7Gg/mLxFFEApC2vk3y7iFg7X8+97ZOp5gIB3dpqMfnAwggrBS+z09Ajezuoj7kp30RLgjWq2iKAM4N3RerKcQfNalkXPYtuKtfK2aPrQBgdBpdksyleWKJpl+IYrrQdnxopBYl0kxQ=" Jun 12 03:13:35 volumio go-librespot[3452]: time="2025-06-12T03:13: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]" Jun 12 03:13:35 volumio go-librespot[3452]: time="2025-06-12T03:13:35Z" level=debug msg="completed keyexchange" Jun 12 03:13:36 volumio go-librespot[3452]: time="2025-06-12T03:13:36Z" level=debug msg="completed challenge" Jun 12 03:13:36 volumio go-librespot[3452]: time="2025-06-12T03:13:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:37 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:37 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233284. Jun 12 03:13:39 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:39 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:39 volumio go-librespot[3474]: Librespot-go daemon starting... Jun 12 03:13:39 volumio go-librespot[3474]: time="2025-06-12T03:13:39Z" level=info msg="generated new device id: 27a87481d4b72121604b91dd723e427afe7519ae" Jun 12 03:13:39 volumio go-librespot[3474]: time="2025-06-12T03:13:39Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:39 volumio go-librespot[3474]: time="2025-06-12T03:13:39Z" level=debug msg="obtained new client token: AACVquymRZ91Nxah99y10o8S5UVTOwQjAVz4q8CuasIinkQS8A9ZJ5ng4JRvCvaAcNy6dLXTdVEID4lPr1JQUtAwoj/aCXoZWNy68I8DBL7GRHnmw1XnBz5JNv9KfTlGM/IbQqM4X5Bk7M+72qj28wSJ8TfXZVaBt+YkgGO5/Qtkreopd4mR/ONERC3/q0MDr1d0Xx8jxLeatcAt0ZcazNRngGTaQgPXY1UWTXfBeAuothrIDD/hEgfPYUdBuOc=" Jun 12 03:13:39 volumio go-librespot[3474]: time="2025-06-12T03:13: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]" Jun 12 03:13:39 volumio go-librespot[3474]: time="2025-06-12T03:13:39Z" level=debug msg="completed keyexchange" Jun 12 03:13:40 volumio go-librespot[3474]: time="2025-06-12T03:13:40Z" level=debug msg="completed challenge" Jun 12 03:13:40 volumio go-librespot[3474]: time="2025-06-12T03:13:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:40 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:40 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233285. Jun 12 03:13:43 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:43 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:43 volumio go-librespot[3482]: Librespot-go daemon starting... Jun 12 03:13:43 volumio go-librespot[3482]: time="2025-06-12T03:13:43Z" level=info msg="generated new device id: 9307e44332de3137b16412179dd549d6bf48ec22" Jun 12 03:13:43 volumio go-librespot[3482]: time="2025-06-12T03:13:43Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:43 volumio go-librespot[3482]: time="2025-06-12T03:13:43Z" level=debug msg="obtained new client token: AABqKpQv1rGWF3a9f95F+LpOUJCa824HYud5YLI8LE5RNFudBEaFUREq7N45bc47VEXWv+QsbN0//h779trjFaIpAfPOkSxqGedUPAt12QM565U0SJxj1eDf6zXpKvFNECZe63SBr+kuoSCKnTbs3vBf/tFuzor1llfomp3++6QZqHcOw1R2XehsWHDbHqZ/30sqI4nKZTyGPugn7IfYmHEf79ls2vLWSCoo3OC8ILHnFCctYAflRkIa7Ob59Wk=" Jun 12 03:13:43 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:43 volumio volumio[877]: info: Connection to go-librespot Websocket established Jun 12 03:13:43 volumio go-librespot[3482]: time="2025-06-12T03:13:43Z" level=debug msg="new websocket client" Jun 12 03:13:43 volumio go-librespot[3482]: time="2025-06-12T03:13: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]" Jun 12 03:13:43 volumio go-librespot[3482]: time="2025-06-12T03:13:43Z" level=debug msg="completed keyexchange" Jun 12 03:13:44 volumio go-librespot[3482]: time="2025-06-12T03:13:44Z" level=debug msg="completed challenge" Jun 12 03:13:44 volumio go-librespot[3482]: time="2025-06-12T03:13:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:44 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jun 12 03:13:46 volumio volumio[877]: info: Getting Spotify volume Jun 12 03:13:46 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:46 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 12 03:13:46 volumio volumio[877]: (node:877) 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: 20916) Jun 12 03:13:46 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 12 03:13:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jun 12 03:13:46 volumio volumio[877]: info: CorePlayQueue::getTrack 19 Jun 12 03:13:46 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 12 03:13:47 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:47 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233286. Jun 12 03:13:47 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:47 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:47 volumio go-librespot[3507]: Librespot-go daemon starting... Jun 12 03:13:47 volumio go-librespot[3507]: time="2025-06-12T03:13:47Z" level=info msg="generated new device id: c25f13526b33aaaa1805e770cb30b0ff55bbcdbc" Jun 12 03:13:47 volumio go-librespot[3507]: time="2025-06-12T03:13:47Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:47 volumio go-librespot[3507]: time="2025-06-12T03:13:47Z" level=debug msg="obtained new client token: AAAQ7OwKGGpYOo8XhxN18ujL8CVOh6bEjk7FAuSv+IL8NHtLh5yWTSgAXY9Rrqp8VMFQdl8R/Pf+AJjF5CtYax9I/6EQ/0nd8su9BeEwQE9pPb2OmRk+uSH2Q9rYe1f/ZJOCiSAMoQIk0cdKh9Ifu18DjF0rZNlYPqyGDnMlJ7FXu0eM+3D9IstKK0dmI1Itz/QPh1dML1wXfPm2i6AFIUXlAzuBEVejkh6HYEjNJCjMW9GuR7kjGeM6JsYQLPY=" Jun 12 03:13:47 volumio go-librespot[3507]: time="2025-06-12T03:13:47Z" 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]" Jun 12 03:13:47 volumio go-librespot[3507]: time="2025-06-12T03:13:47Z" level=debug msg="completed keyexchange" Jun 12 03:13:48 volumio go-librespot[3507]: time="2025-06-12T03:13:48Z" level=debug msg="completed challenge" Jun 12 03:13:48 volumio go-librespot[3507]: time="2025-06-12T03:13:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:50 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:50 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233287. Jun 12 03:13:51 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:51 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:51 volumio go-librespot[3515]: Librespot-go daemon starting... Jun 12 03:13:51 volumio go-librespot[3515]: time="2025-06-12T03:13:51Z" level=info msg="generated new device id: bd139fb9e320b6904eacbb6909cdd22bc5eb95b0" Jun 12 03:13:51 volumio go-librespot[3515]: time="2025-06-12T03:13:51Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:51 volumio go-librespot[3515]: time="2025-06-12T03:13:51Z" level=debug msg="obtained new client token: AAC/c+3ZQTuUg/fElQ3pYvqdtqzQ/rh2WoWSreHZgqSLnvBQui0FQatoAipzKS2pFUlL3UQ02FFtmZGITejANXAoPq5h96dA4JvOYmI49xUlvD5lNCOqIXdmPHK6oKU+riXNxe28p7U8uToJuqkVa1nglGFo22J0GbkFmLXh1cli8STpWY/Z+OMJkFmcaCqtIWR1jv8YEHXSo+gK4GCaBEOLmXwrzwDMmHSvKnVWfmq4aLA0/DH3ZEWtl9j38p4=" Jun 12 03:13:51 volumio go-librespot[3515]: time="2025-06-12T03:13:51Z" 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]" Jun 12 03:13:51 volumio go-librespot[3515]: time="2025-06-12T03:13:51Z" level=debug msg="completed keyexchange" Jun 12 03:13:52 volumio go-librespot[3515]: time="2025-06-12T03:13:52Z" level=debug msg="completed challenge" Jun 12 03:13:52 volumio go-librespot[3515]: time="2025-06-12T03:13:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:53 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:53 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233288. Jun 12 03:13:55 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:55 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:55 volumio go-librespot[3523]: Librespot-go daemon starting... Jun 12 03:13:55 volumio go-librespot[3523]: time="2025-06-12T03:13:55Z" level=info msg="generated new device id: 56113a50d2a9f0e738f15332fb1ce6b8e41aca14" Jun 12 03:13:55 volumio go-librespot[3523]: time="2025-06-12T03:13:55Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:55 volumio go-librespot[3523]: time="2025-06-12T03:13:55Z" level=debug msg="obtained new client token: AAD15V28/R4UBYufw+RQ4J6/jdVAhzAUuBl8SGfvvN0W4MKgeyopCapKhgQSyhWKKVJfiqb8Pv1v4vCyVtCkmrlT8pf8Rk1ph9/VorXm/VqkAuzjCV8YIBY9fDQoKKQvPMhLzKedDTMiaVWHMo7zN43Rf8fcOQaXmOjIQRaPoY8v+H9ccZN/8HRAEDrUrsD2BoP79nDodIT5R9hRiL5YTxuN1sUV8nxe9hK+lLpZ//4sRwJHHYb34ml2GySvUz0=" Jun 12 03:13:55 volumio go-librespot[3523]: time="2025-06-12T03:13: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 12 03:13:55 volumio go-librespot[3523]: time="2025-06-12T03:13:55Z" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Jun 12 03:13:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:56 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:56 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:13:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:13:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233289. Jun 12 03:13:58 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:13:58 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:13:58 volumio go-librespot[3545]: Librespot-go daemon starting... Jun 12 03:13:58 volumio go-librespot[3545]: time="2025-06-12T03:13:58Z" level=info msg="generated new device id: 26b790667288eb552a396026795a261dde59794d" Jun 12 03:13:58 volumio go-librespot[3545]: time="2025-06-12T03:13:58Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:13:59 volumio go-librespot[3545]: time="2025-06-12T03:13:59Z" level=debug msg="obtained new client token: AADnw5krGW8Kw0gdr5WrkKesgT+GnoEylGgVzsdC1dVhcfuL00AxvD0DulrBT2HPQ0jGELLCl9BCkYPPUB+KHKMPMHjSWtc/KCf+SragcvU+S38E/nNbejqC6F9kVKFDr7qkLX14ZLB6Uw0UO4zsEbUX96lg0arlLWJRxjJPfAGtFjVA2rI0fV2Sa6yIr/mb6+ID1u7Ux65Z+EQQvGcQhlRAkMwVH3o1aw71MpqXYZeJpMPkDdlrkokHDq/H" Jun 12 03:13:59 volumio volumio-remote-updater[673]: zsync done Jun 12 03:13:59 volumio go-librespot[3545]: time="2025-06-12T03:13: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]" Jun 12 03:13:59 volumio go-librespot[3545]: time="2025-06-12T03:13:59Z" level=debug msg="completed keyexchange" Jun 12 03:13:59 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:13:59 volumio go-librespot[3545]: time="2025-06-12T03:13:59Z" level=debug msg="new websocket client" Jun 12 03:13:59 volumio volumio[877]: info: Connection to go-librespot Websocket established Jun 12 03:13:59 volumio go-librespot[3545]: time="2025-06-12T03:13:59Z" level=debug msg="completed challenge" Jun 12 03:13:59 volumio go-librespot[3545]: time="2025-06-12T03:13:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:13:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:13:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:13:59 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jun 12 03:14:02 volumio volumio[877]: info: Getting Spotify volume Jun 12 03:14:02 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:02 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 12 03:14:02 volumio volumio[877]: (node:877) 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: 20917) Jun 12 03:14:02 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 12 03:14:02 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jun 12 03:14:02 volumio volumio[877]: info: CorePlayQueue::getTrack 19 Jun 12 03:14:02 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 12 03:14:02 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:02 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233290. Jun 12 03:14:02 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:02 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:02 volumio go-librespot[3556]: Librespot-go daemon starting... Jun 12 03:14:02 volumio go-librespot[3556]: time="2025-06-12T03:14:02Z" level=info msg="generated new device id: 7b492eb3998dd1ffc9096d36bb15645abf2d3515" Jun 12 03:14:02 volumio go-librespot[3556]: time="2025-06-12T03:14:02Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:03 volumio go-librespot[3556]: time="2025-06-12T03:14:03Z" level=debug msg="obtained new client token: AACc/8hNCBHE9lDAZV7FENmgs3JmZ6sYCE0wyw82v9cSQl/XgxlHBgvumd6XyRKkdGswrZgf8rDeuu9Ox/rGcLHdd8kbdG1OmAargzgq2QxxPVva4d1angyePVEzvCMk9wIbNz4Z2Dw3x88pBhBoHGpKbSXsigfEvCuonez8JLHUE6xno+IlmxXyZ+zflKq6iGUztdKiuoOOzi1OBzP0RwqYMIrZWuSYhz88c/wmCNaUEYYUg2jm0q5Somgo" Jun 12 03:14:03 volumio go-librespot[3556]: time="2025-06-12T03:14: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]" Jun 12 03:14:03 volumio go-librespot[3556]: time="2025-06-12T03:14:03Z" level=debug msg="completed keyexchange" Jun 12 03:14:03 volumio go-librespot[3556]: time="2025-06-12T03:14:03Z" level=debug msg="completed challenge" Jun 12 03:14:03 volumio go-librespot[3556]: time="2025-06-12T03:14:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:05 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:05 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233291. Jun 12 03:14:06 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:06 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:06 volumio go-librespot[3581]: Librespot-go daemon starting... Jun 12 03:14:06 volumio go-librespot[3581]: time="2025-06-12T03:14:06Z" level=info msg="generated new device id: 7bee5e8b12b2278a564fd819532745ad41044f8d" Jun 12 03:14:06 volumio go-librespot[3581]: time="2025-06-12T03:14:06Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:07 volumio go-librespot[3581]: time="2025-06-12T03:14:07Z" level=debug msg="obtained new client token: AACzjVzLFYU2lcfkRvunJNfms8LkDJdt3WgVZlLXZhZXuyFgcjKaM6O0rzkdeuoHZqmvj/eeBQT3MJVKzwUw/pfAohnpJ9Pzbtg89l5x5fUgMfE6qEz7xuZxk0eFfKMGtevUsqPNCYe/j8SagwcIoh+KtN0T3Ay32QL+kJq9um2Pn62XCKKkaXK5EuqQ67+SoOY6pvpf0Hu2hQfTK2FZlqwKogIeDUDKMb/GXDO0qSVQ3KySNH8CHOiVGgpP" Jun 12 03:14:07 volumio go-librespot[3581]: time="2025-06-12T03:14: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]" Jun 12 03:14:07 volumio go-librespot[3581]: time="2025-06-12T03:14:07Z" level=debug msg="completed keyexchange" Jun 12 03:14:07 volumio go-librespot[3581]: time="2025-06-12T03:14:07Z" level=debug msg="completed challenge" Jun 12 03:14:07 volumio go-librespot[3581]: time="2025-06-12T03:14:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:08 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:08 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:09 volumio volumio-remote-updater[673]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m" Jun 12 03:14:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:09 volumio volumio[877]: updateProgress Jun 12 03:14:09 volumio volumio[877]: { Jun 12 03:14:09 volumio volumio[877]: downloadSpeed: '', Jun 12 03:14:09 volumio volumio[877]: eta: '2m', Jun 12 03:14:09 volumio volumio[877]: progress: 80, Jun 12 03:14:09 volumio volumio[877]: status: 'Downloading new update' Jun 12 03:14:09 volumio volumio[877]: } Jun 12 03:14:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233292. Jun 12 03:14:10 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:10 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:10 volumio go-librespot[3593]: Librespot-go daemon starting... Jun 12 03:14:10 volumio go-librespot[3593]: time="2025-06-12T03:14:10Z" level=info msg="generated new device id: c9f983d3a913320cbcd3f98fcf65d06931321e89" Jun 12 03:14:10 volumio go-librespot[3593]: time="2025-06-12T03:14:10Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:11 volumio go-librespot[3593]: time="2025-06-12T03:14:11Z" level=debug msg="obtained new client token: AAAHL+gC5Ho8dQgT+bRwGWf9CsclQ42W4kRaCzC9PaH8TF+xfs9VJLXER+0g3qvZH7t+Prp4WCKdHkcFX3ShoEd5Dj6AvkDkhn97LAbA49zDG131ML4YdYiUdc2EV7l8z2V3eJaxU0YgCBAfPNsLbFj3g7j4oU3XppZvC9HWRnik95dIpT2lqKWWx7EfT0BEF9ASbB0B/gkgFoOccKKmU9+GqHD6zt4yYySUKYHP+TswqLKVtC437wAJM5wB" Jun 12 03:14:11 volumio go-librespot[3593]: time="2025-06-12T03:14: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]" Jun 12 03:14:11 volumio go-librespot[3593]: time="2025-06-12T03:14:11Z" level=debug msg="completed keyexchange" Jun 12 03:14:11 volumio go-librespot[3593]: time="2025-06-12T03:14:11Z" level=debug msg="completed challenge" Jun 12 03:14:11 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:11 volumio go-librespot[3593]: time="2025-06-12T03:14:11Z" level=debug msg="new websocket client" Jun 12 03:14:11 volumio volumio[877]: info: Connection to go-librespot Websocket established Jun 12 03:14:11 volumio go-librespot[3593]: time="2025-06-12T03:14:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:11 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jun 12 03:14:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:13 volumio volumio-remote-updater[673]: zsync done Jun 12 03:14:14 volumio volumio[877]: info: Getting Spotify volume Jun 12 03:14:14 volumio volumio[877]: (node:877) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:14 volumio volumio[877]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 12 03:14:14 volumio volumio[877]: (node:877) 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: 20918) Jun 12 03:14:14 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 12 03:14:14 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Jun 12 03:14:14 volumio volumio[877]: info: CorePlayQueue::getTrack 19 Jun 12 03:14:14 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 12 03:14:14 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:14 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233293. Jun 12 03:14:14 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:14 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:14 volumio go-librespot[3677]: Librespot-go daemon starting... Jun 12 03:14:14 volumio go-librespot[3677]: time="2025-06-12T03:14:14Z" level=info msg="generated new device id: f96d7e8de7cdbdb8c16b4faf7982f92cea6603c2" Jun 12 03:14:14 volumio go-librespot[3677]: time="2025-06-12T03:14:14Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:15 volumio go-librespot[3677]: time="2025-06-12T03:14:15Z" level=debug msg="obtained new client token: AAD0BxRDWswKMuzE3GPaqzk3rWiPFlhHqlQbYP2fTG5Qq6TTvP3D6YbpTsM6vWzzW+kOZX+S4zJ4iVxNbEXLXeuZQWKKySenZmzBZGBHMYYBdYgt3ZuCEu7qcYZ+6DVA5i6EI2TBefSAzf6hqvCF8wvBYYDfnjXjh1t3vvgjt3q22VU5C8LVwPFz7PIKukt8VRNlGC38i6XoGZUsxKDoCoF1Dphv6Y0tJ714a8ZeVbFF3D4QLlzI1SAaYkNI" Jun 12 03:14:15 volumio go-librespot[3677]: time="2025-06-12T03:14: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]" Jun 12 03:14:15 volumio go-librespot[3677]: time="2025-06-12T03:14:15Z" level=debug msg="completed keyexchange" Jun 12 03:14:15 volumio go-librespot[3677]: time="2025-06-12T03:14:15Z" level=debug msg="completed challenge" Jun 12 03:14:15 volumio go-librespot[3677]: time="2025-06-12T03:14:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:17 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:17 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233294. Jun 12 03:14:18 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:18 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:18 volumio go-librespot[3699]: Librespot-go daemon starting... Jun 12 03:14:18 volumio go-librespot[3699]: time="2025-06-12T03:14:18Z" level=info msg="generated new device id: f92cd7db265327f8b93b7fe7e7afeaa7e87e3509" Jun 12 03:14:18 volumio go-librespot[3699]: time="2025-06-12T03:14:18Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:19 volumio go-librespot[3699]: time="2025-06-12T03:14:19Z" level=debug msg="obtained new client token: AABwO0X0QRVy2vlZi+Vr8iKUy928d8EFBK06Kmypg0KS6dyTfTWrs63/+J3C/j0uszco27J7a4GHUvEfqAi7t2DSxCaNBByAG2dqT8qqoBtSGubUMSrsdWgPfa76zg5optxDFyMTGJWzBx1AsVFNrNDRTQ4PlVhTzaZ7+Latefx2XcG3EQ68c7rkQpvD0fdWXj2sLuhk53+G+hC6tgvz89Y1Qbc6kqhb0/y7ik0/e3gFWHoT+fV39piCEJX2" Jun 12 03:14:19 volumio go-librespot[3699]: time="2025-06-12T03:14: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-gae2.spotify.com:80]" Jun 12 03:14:19 volumio go-librespot[3699]: time="2025-06-12T03:14:19Z" level=debug msg="completed keyexchange" Jun 12 03:14:19 volumio go-librespot[3699]: time="2025-06-12T03:14:19Z" level=debug msg="completed challenge" Jun 12 03:14:19 volumio go-librespot[3699]: time="2025-06-12T03:14:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:20 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:20 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:22 volumio volumio-remote-updater[673]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m" Jun 12 03:14:22 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:22 volumio volumio[877]: updateProgress Jun 12 03:14:22 volumio volumio[877]: { Jun 12 03:14:22 volumio volumio[877]: downloadSpeed: '', Jun 12 03:14:22 volumio volumio[877]: eta: '1m', Jun 12 03:14:22 volumio volumio[877]: progress: 90, Jun 12 03:14:22 volumio volumio[877]: status: 'Cleaning old files' Jun 12 03:14:22 volumio volumio[877]: } Jun 12 03:14:22 volumio volumio-remote-updater[673]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory Jun 12 03:14:22 volumio volumio-remote-updater[673]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory Jun 12 03:14:22 volumio volumio-remote-updater[673]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s" Jun 12 03:14:22 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:22 volumio volumio[877]: updateProgress Jun 12 03:14:22 volumio volumio[877]: { Jun 12 03:14:22 volumio volumio[877]: downloadSpeed: '', Jun 12 03:14:22 volumio volumio[877]: eta: '30s', Jun 12 03:14:22 volumio volumio[877]: progress: 95, Jun 12 03:14:22 volumio volumio[877]: status: 'Finalizing update' Jun 12 03:14:22 volumio volumio[877]: } Jun 12 03:14:22 volumio volumio-remote-updater[673]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.816\" version. System restart required." Jun 12 03:14:22 volumio volumio-remote-updater[673]: No test mode Jun 12 03:14:22 volumio volumio-remote-updater[673]: No alpha test mode Jun 12 03:14:22 volumio volumio-remote-updater[673]: [2025-06-12 03:14:22] [disconnect] Disconnect close local:[1000] remote:[1000] Jun 12 03:14:22 volumio volumio-remote-updater[673]: [2025-06-12 03:14:22] [info] asio async_write error: system:32 (Broken pipe) Jun 12 03:14:22 volumio volumio-remote-updater[673]: [2025-06-12 03:14:22] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error) Jun 12 03:14:22 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:22 volumio volumio[877]: updateReady Jun 12 03:14:22 volumio volumio[877]: { Jun 12 03:14:22 volumio volumio[877]: changeLogLink: 'http://volumio.org', Jun 12 03:14:22 volumio volumio[877]: description: '

FIXES



 

', Jun 12 03:14:22 volumio volumio[877]: title: 'Update v3.816', Jun 12 03:14:22 volumio volumio[877]: updateavailable: true Jun 12 03:14:22 volumio volumio[877]: } Jun 12 03:14:22 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Jun 12 03:14:22 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jun 12 03:14:22 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jun 12 03:14:22 volumio systemd[1]: Stopped volumio-remote-updater.service. Jun 12 03:14:22 volumio systemd[1]: Started volumio-remote-updater.service. Jun 12 03:14:22 volumio volumio-remote-updater[3721]: Error: No active session Jun 12 03:14:22 volumio volumio-remote-updater[3721]: [2025-06-12 03:14:22] [connect] Successful connection Jun 12 03:14:22 volumio volumio-remote-updater[3721]: [2025-06-12 03:14:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749698062 101 Jun 12 03:14:22 volumio volumio[877]: 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 Jun 12 03:14:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233295. Jun 12 03:14:22 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:22 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:22 volumio go-librespot[3725]: Librespot-go daemon starting... Jun 12 03:14:22 volumio go-librespot[3725]: time="2025-06-12T03:14:22Z" level=info msg="generated new device id: 6dfb33f1ac4625d4d8383aef0da15deacbbd9dd4" Jun 12 03:14:22 volumio go-librespot[3725]: time="2025-06-12T03:14:22Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:23 volumio go-librespot[3725]: time="2025-06-12T03:14:23Z" level=debug msg="obtained new client token: AAAcsmTwkQhi9kCeQ9RQe/aLTjT/LJmCgyZsxffIejc7dhNSJbKQHg9lqdql4gxyPUZl7NdAL99p83+e79278xQyDBdPMF3uVh//fdyVZGLJRWAX13hf/wFUFJ3Rg1aqejlmGczGZ+N7uvxYcIxUA/8zlgxi/tqyx/sHMxXoE1pVHFaUHv9I1XnXaCTDLjpSwOijeJL+7xK0hnEVuFCtBObZzwoNChWE53OQd8L4+4VDpSEEQYxy7tLHQJwh" Jun 12 03:14:23 volumio go-librespot[3725]: time="2025-06-12T03:14: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 12 03:14:23 volumio go-librespot[3725]: time="2025-06-12T03:14:23Z" level=debug msg="completed keyexchange" Jun 12 03:14:23 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:23 volumio go-librespot[3725]: time="2025-06-12T03:14:23Z" level=debug msg="completed challenge" Jun 12 03:14:23 volumio go-librespot[3725]: time="2025-06-12T03:14:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:23 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:23 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:24 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:25 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:26 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:26 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:26 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233296. Jun 12 03:14:26 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:26 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:26 volumio go-librespot[3750]: Librespot-go daemon starting... Jun 12 03:14:26 volumio go-librespot[3750]: time="2025-06-12T03:14:26Z" level=info msg="generated new device id: b3655230afa57301d41ce7d09b9bfd1bb75bcc0d" Jun 12 03:14:26 volumio go-librespot[3750]: time="2025-06-12T03:14:26Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:27 volumio go-librespot[3750]: time="2025-06-12T03:14:27Z" level=debug msg="obtained new client token: AACua9ihlFHlQLywc3V/SCMEpTP8V3F5/3jrIm8Hb/KyivCOomTVEnUW9n5+2ePnlQUrxi9VDTnO3I7dH9VkR8YEwmk8Iwl5cTuiuie1MmBEcrTC2u+Z3uTZHWN/wFU37jmJrOGIlOPFvBTL9pEGOZKQN1EsFJ7Ai7zlsYBf0c1+mxYyX/fImayq/QCv64n54QyyoKsq9G3NxxtusW/H/O61VVzsvkwXd3zK9VwhtEoOsnmLapKDvFb4uDuS" Jun 12 03:14:27 volumio go-librespot[3750]: time="2025-06-12T03:14: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-gae2.spotify.com:80]" Jun 12 03:14:27 volumio go-librespot[3750]: time="2025-06-12T03:14:27Z" level=debug msg="completed keyexchange" Jun 12 03:14:27 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:27 volumio go-librespot[3750]: time="2025-06-12T03:14:27Z" level=debug msg="completed challenge" Jun 12 03:14:27 volumio go-librespot[3750]: time="2025-06-12T03:14:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:28 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:29 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:29 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:29 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:30 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233297. Jun 12 03:14:30 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:30 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:30 volumio go-librespot[3758]: Librespot-go daemon starting... Jun 12 03:14:30 volumio go-librespot[3758]: time="2025-06-12T03:14:30Z" level=info msg="generated new device id: 7b37e1bdc86f96bf06f1046c0dd90ca150bb6ab0" Jun 12 03:14:30 volumio go-librespot[3758]: time="2025-06-12T03:14:30Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:31 volumio go-librespot[3758]: time="2025-06-12T03:14:31Z" level=debug msg="obtained new client token: AADSpGhAy2hsTsIw9Ck/9lIwOlFtCwzfP1TmJabsPHUjlU3IymhX3C/h7WghYHAnK5ptVRWih2HSNY3D45FRPn2nODlEy/gYHYu/N5liv1O4K7uWdMkxlXWVvUwAB/0VelolWUyzxKyqjw+wIWkf+x3zZn/ZglcQ8fBcM7yBLjC6ZqM3w9Je0s/s9Fea0d+Z5Hzv202ivlyZlFUFwnJZEIXRtkEmnJCjz9jJouOdf+9/LelgAMl9z5xCCVOW" Jun 12 03:14:31 volumio go-librespot[3758]: time="2025-06-12T03:14:31Z" 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]" Jun 12 03:14:31 volumio go-librespot[3758]: time="2025-06-12T03:14:31Z" level=debug msg="completed keyexchange" Jun 12 03:14:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:31 volumio go-librespot[3758]: time="2025-06-12T03:14:31Z" level=debug msg="completed challenge" Jun 12 03:14:32 volumio go-librespot[3758]: time="2025-06-12T03:14:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:32 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:32 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:32 volumio volumio[877]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 12 03:14:33 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:34 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233298. Jun 12 03:14:35 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:35 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:35 volumio go-librespot[3766]: Librespot-go daemon starting... Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=info msg="generated new device id: 868c8fc6cd859c006627ce9837256ea1b173abc6" Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=debug msg="obtained new client token: AACRic2gf8Xx47HcHEpWf/PdeDUQaJg2X9xQLMXPy9Gf6CjhETGN2W2WVVKhHUIghrvoinZWsm4l08VHa1+6seM7vPaN6HWnC/Oxo7+FM9M8a/KsJBK1liVGp7AniEgW8lReQZbPdnxB0BKBBrnt+ZKdOgsYtpL4XY25iOijjmiLKzLoG12yeileHIuGEAthG5PA6/jv+LtLyRTizU27LLGmgwY9Iu1zbzBl/A2TOWLKVPFjFp/VcspJ9IGd++U=" Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14: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-gae2.spotify.com:80]" Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=debug msg="completed keyexchange" Jun 12 03:14:35 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:35 volumio volumio[877]: info: Initializing connection to go-librespot Websocket Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=debug msg="new websocket client" Jun 12 03:14:35 volumio volumio[877]: info: Connection to go-librespot Websocket established Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=debug msg="completed challenge" Jun 12 03:14:35 volumio go-librespot[3766]: time="2025-06-12T03:14:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:35 volumio volumio[877]: info: Connection to go-librespot Websocket closed Jun 12 03:14:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:37 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jun 12 03:14:38 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent Jun 12 03:14:38 volumio volumio[877]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jun 12 03:14:38 volumio volumio[877]: info: PLUGIN onReboot : networkfs Jun 12 03:14:38 volumio sudo[3789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/DSD_MUSIC Jun 12 03:14:38 volumio sudo[3789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 03:14:38 volumio systemd[1]: mnt-NAS-DSD_MUSIC.mount: Succeeded. Jun 12 03:14:38 volumio sudo[3789]: pam_unix(sudo:session): session closed for user root Jun 12 03:14:38 volumio sudo[3796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Leftovers Jun 12 03:14:38 volumio sudo[3796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 03:14:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233299. Jun 12 03:14:39 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:39 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:39 volumio go-librespot[3800]: Librespot-go daemon starting... Jun 12 03:14:39 volumio go-librespot[3800]: time="2025-06-12T03:14:39Z" level=info msg="generated new device id: 4f5664f3a04869e1d642b1815b956d5d3e5485c9" Jun 12 03:14:39 volumio go-librespot[3800]: time="2025-06-12T03:14:39Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:39 volumio go-librespot[3800]: time="2025-06-12T03:14:39Z" level=debug msg="obtained new client token: AADrSjix80EfkFmsw78o56lvBi+cFoSvMtT8ZKp5VUyOVb+MS83fuQUCbt+VDLP2E2SS2pnPEgwV0hsPsO9tkkzKXf8cRjb/jtqsA1KpHdx2ka7XVgmWvXAhGWwUintp1WIrWvLaHERfY50KHCeSBJXJweRoeEDVqVTGv5Ze+qtRQo47uE7585U759dvNL1XkpUBFSPEYrj4CBho3Oz6RPpADUKP4jAEQRBKIhwAt5ASSMVdZ7vyzySUX+LYGwY=" Jun 12 03:14:39 volumio go-librespot[3800]: time="2025-06-12T03:14: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]" Jun 12 03:14:39 volumio go-librespot[3800]: time="2025-06-12T03:14:39Z" level=debug msg="completed keyexchange" Jun 12 03:14:39 volumio go-librespot[3800]: time="2025-06-12T03:14:39Z" level=debug msg="completed challenge" Jun 12 03:14:40 volumio go-librespot[3800]: time="2025-06-12T03:14:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233300. Jun 12 03:14:43 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:43 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:43 volumio go-librespot[3809]: Librespot-go daemon starting... Jun 12 03:14:43 volumio go-librespot[3809]: time="2025-06-12T03:14:43Z" level=info msg="generated new device id: b02b9dc8e9f6ce3fdb4d99d23b99aa685dffb008" Jun 12 03:14:43 volumio go-librespot[3809]: time="2025-06-12T03:14:43Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:43 volumio go-librespot[3809]: time="2025-06-12T03:14:43Z" level=debug msg="obtained new client token: AABjiyntrBL9/oe9zZXyT+wEsTSzTQoaGWarwFpnA3uX6eBo+5Kovoxg15UY+yUO4yZva/kv8Gj3fmNf9E2rg8JJgnZq68tl0KGoP4V8KH96aRU1ndBeHRxOoGXD9yFbkuLG+0Rpw9m166+kkqlTVZ0rp+u/Tz124hEKGfQ1Iyn/bave568yXgeU056j7YFizxwQtc4TjyqRx8aOYWDxfQL54ijiu6D2sDGiPGq6GIkS/7ZxyirHNOt75vrhzMI=" Jun 12 03:14:43 volumio go-librespot[3809]: time="2025-06-12T03:14: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-gae2.spotify.com:80]" Jun 12 03:14:43 volumio go-librespot[3809]: time="2025-06-12T03:14:43Z" level=debug msg="completed keyexchange" Jun 12 03:14:43 volumio go-librespot[3809]: time="2025-06-12T03:14:43Z" level=debug msg="completed challenge" Jun 12 03:14:44 volumio go-librespot[3809]: time="2025-06-12T03:14:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233301. Jun 12 03:14:47 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:47 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:47 volumio go-librespot[3835]: Librespot-go daemon starting... Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" level=info msg="generated new device id: 90c9c79e55f4b9ac0d662cabab41fd97dd60c908" Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" level=debug msg="obtained new client token: AAB9pEaZvOzih/6KnLXQFzv5RXL8L16ksqzmzritSol5gyt+dq/FKRsV3CqglCwOpsxady1n072jf4ttmXN1EILR32fOkpZfWNZEL/gzZh77MeoeXKImw55FWXAvrB6GkSdSnMFetsOtPYo8bGJZXiHqf7D8sPkZ1ciRx3iXn881+7SoOtVlE9nFbwa+pekG8KiuI11zTpGQH8Q4k4MuA0H4sE2z+E3t35YIZ0860vWB2S2MYPgnSATrfs292qM=" Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" 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]" Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" level=debug msg="completed keyexchange" Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" level=debug msg="completed challenge" Jun 12 03:14:47 volumio go-librespot[3835]: time="2025-06-12T03:14:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:48 volumio volumio[877]: error: Cannot umount share Leftovers : Error: spawnSync /bin/sh ETIMEDOUT Jun 12 03:14:48 volumio sudo[3844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Music Jun 12 03:14:48 volumio sudo[3844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 03:14:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 12 03:14:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233302. Jun 12 03:14:51 volumio systemd[1]: Stopped go-librespot Daemon. Jun 12 03:14:51 volumio systemd[1]: Started go-librespot Daemon. Jun 12 03:14:51 volumio go-librespot[3847]: Librespot-go daemon starting... Jun 12 03:14:51 volumio go-librespot[3847]: time="2025-06-12T03:14:51Z" level=info msg="generated new device id: 3fb30971a461c4c172ca36bf85a435b89a33197a" Jun 12 03:14:51 volumio go-librespot[3847]: time="2025-06-12T03:14:51Z" level=debug msg="stored credentials found for adonzell" Jun 12 03:14:51 volumio go-librespot[3847]: time="2025-06-12T03:14:51Z" level=debug msg="obtained new client token: AAAWUxspfs1axCPcKUPaRvhAfac8DfHWLArFxBVTxAtCfRnhx+bq1jKvJE/hsWN0aNSMymheOcrONZCZ5Q3UbetvyPlAT4DtCezTLKAY+nevuH6V+qzPvtpjv3bov+2PzgbMj1ON8YvQVfjI9GcF9lWKsXp3en2nhvABHqMoYL+52f6W8lClJ0QHs7QlBNqBFHOHCffUKINiijAdV9/9+Kmj/pv8y1h8tT5zvaqL/TYLF7Cvl8eZlqvTLEPTMJs=" Jun 12 03:14:51 volumio go-librespot[3847]: time="2025-06-12T03:14:51Z" 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]" Jun 12 03:14:51 volumio go-librespot[3847]: time="2025-06-12T03:14:51Z" level=debug msg="completed keyexchange" Jun 12 03:14:51 volumio go-librespot[3847]: time="2025-06-12T03:14:51Z" level=debug msg="completed challenge" Jun 12 03:14:52 volumio go-librespot[3847]: time="2025-06-12T03:14:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 12 03:14:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 12 03:14:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 12 03:14:52 volumio volumio-remote-updater[3721]: [2025-06-12 03:14:52] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jun 12 03:14:53 volumio sudo[3844]: pam_unix(sudo:session): session closed for user root Jun 12 03:14:53 volumio volumio[877]: umount.nfs: /mnt/NAS/Music: device is busy Jun 12 03:14:53 volumio volumio[877]: error: Cannot umount share Music : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/Music Jun 12 03:14:53 volumio volumio[877]: umount.nfs: /mnt/NAS/Music: device is busy Jun 12 03:14:53 volumio volumio[877]: info: PLUGIN onReboot : audiophonicsonoff Jun 12 03:14:53 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 03:14:53 volumio volumio[877]: TypeError: Cannot read property 'writeSync' of undefined Jun 12 03:14:53 volumio volumio[877]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jun 12 03:14:53 volumio volumio[877]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jun 12 03:14:53 volumio volumio[877]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jun 12 03:14:53 volumio volumio[877]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 12 03:14:53 volumio volumio[877]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 12 03:14:53 volumio volumio[877]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jun 12 03:14:53 volumio volumio[877]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Jun 12 03:14:53 volumio volumio[877]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/updater_comm/index.js:156:35) Jun 12 03:14:53 volumio volumio[877]: at listOnTimeout (internal/timers.js:554:17) Jun 12 03:14:53 volumio volumio[877]: at processTimers (internal/timers.js:497:7) Jun 12 03:14:53 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 03:14:53 volumio sudo[3864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 03:13 Jun 12 03:14:53 volumio sudo[3864]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"