-- Logs begin at Fri 2025-12-19 20:29:11 CST, end at Fri 2025-12-19 21:15:48 CST. -- Dec 19 21:14:00 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:01 volumio go-librespot[7803]: time="2025-12-19T21:14:01+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:14:01 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:01 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Dec 19 21:14:01 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:01 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:01 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:01 volumio go-librespot[7803]: time="2025-12-19T21:14:01+08:00" level=debug msg="completed keyexchange" Dec 19 21:14:02 volumio go-librespot[7803]: time="2025-12-19T21:14:02+08:00" level=debug msg="completed challenge" Dec 19 21:14:02 volumio go-librespot[7803]: time="2025-12-19T21:14:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:14:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:02 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 19 21:14:02 volumio volumio[935]: at connResetException (internal/errors.js:607:14) Dec 19 21:14:02 volumio volumio[935]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 19 21:14:02 volumio volumio[935]: at Socket.emit (events.js:327:22) Dec 19 21:14:02 volumio volumio[935]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 19 21:14:02 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 19 21:14:02 volumio volumio[935]: (node:935) 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: 339) Dec 19 21:14:02 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:05 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:05 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 337. Dec 19 21:14:05 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:05 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:05 volumio go-librespot[7813]: Librespot-go daemon starting... Dec 19 21:14:05 volumio go-librespot[7813]: time="2025-12-19T21:14:05+08:00" level=info msg="generated new device id: 59ec333590164d39335f7e08d3bc77ee25569e1b" Dec 19 21:14:05 volumio go-librespot[7813]: time="2025-12-19T21:14:05+08:00" level=debug msg="stored credentials not found" Dec 19 21:14:06 volumio go-librespot[7813]: time="2025-12-19T21:14:06+08:00" level=debug msg="obtained new client token: AAAhLCqWsUNafmNtv1mKI+CH90qg32L1hCnjPpVZbqgT4IiFlZVNC7HZ6HPTjy0kKEAnaYF0sU/qTdk+fi+9s1i1v0EhzLFBeVJvfhUnaKNCFwHbrlXvBw/OE66JmVltI+owQ00xZQtdbPsKYsD2tt0W+0zrTDXO3LOt0JbSFtcJ6neXCeK1PTAABgir9QlaDgJlsXOR9hlTqNyCyWCS9Ejn3JkCP+EUr66nrDiQy/rOAJWPB6OwihBUjyrFz8hE" Dec 19 21:14:06 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:07 volumio go-librespot[7813]: time="2025-12-19T21:14:07+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:14:08 volumio go-librespot[7813]: time="2025-12-19T21:14:08+08:00" level=debug msg="completed keyexchange" Dec 19 21:14:08 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:08 volumio go-librespot[7813]: time="2025-12-19T21:14:08+08:00" level=debug msg="new websocket client" Dec 19 21:14:08 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:14:08 volumio go-librespot[7813]: time="2025-12-19T21:14:08+08:00" level=debug msg="completed challenge" Dec 19 21:14:08 volumio go-librespot[7813]: time="2025-12-19T21:14:08+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:14:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:08 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:11 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:11 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:11 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:14:11 volumio volumio[935]: (node:935) 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: 340) Dec 19 21:14:11 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:14:11 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:11 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:11 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:11 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:11 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 338. Dec 19 21:14:12 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:12 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:12 volumio go-librespot[7821]: Librespot-go daemon starting... Dec 19 21:14:12 volumio go-librespot[7821]: time="2025-12-19T21:14:12+08:00" level=info msg="generated new device id: 8efc66e78ecdd6afa669878c72b6d32d3f9fe1d5" Dec 19 21:14:12 volumio go-librespot[7821]: time="2025-12-19T21:14:12+08:00" level=debug msg="stored credentials not found" Dec 19 21:14:12 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:13 volumio go-librespot[7821]: time="2025-12-19T21:14:13+08:00" level=debug msg="obtained new client token: AACJwQg3J4OnoT9UiIqrO6FI+bOUxoBSxt/i7gVHc4V179IMNP9maXyVpPq0JWbF6Y81dy+YpadotppNnUrws9w6fbeBKNdRSqaIS+NTZGYK5aZHxON2HzNt26NVMuacYmjOvNHhxdcKqM18QszjNfJDSTRhKC55xbXVjj2XHQD2n4BTJuRHR6hqqE/ySE0PHKelKv8mUqQF61GsNwR5n3ifXYaW4eOQtyUZQ6p9sQ8kr3xujs2qBY9kEyihJA==" Dec 19 21:14:14 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:14 volumio go-librespot[7821]: time="2025-12-19T21:14:14+08:00" level=debug msg="new websocket client" Dec 19 21:14:14 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:14:16 volumio go-librespot[7821]: time="2025-12-19T21:14:16+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:14:17 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:17 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:14:17 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:17 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:17 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:18 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:19 volumio go-librespot[7821]: time="2025-12-19T21:14:19+08:00" level=debug msg="completed keyexchange" Dec 19 21:14:19 volumio go-librespot[7821]: time="2025-12-19T21:14:19+08:00" level=debug msg="completed challenge" Dec 19 21:14:20 volumio go-librespot[7821]: time="2025-12-19T21:14:20+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:14:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:20 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 19 21:14:20 volumio volumio[935]: at connResetException (internal/errors.js:607:14) Dec 19 21:14:20 volumio volumio[935]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 19 21:14:20 volumio volumio[935]: at Socket.emit (events.js:327:22) Dec 19 21:14:20 volumio volumio[935]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 19 21:14:20 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 19 21:14:20 volumio volumio[935]: (node:935) 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: 341) Dec 19 21:14:20 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:23 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:23 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 339. Dec 19 21:14:23 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:23 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:23 volumio go-librespot[7833]: Librespot-go daemon starting... Dec 19 21:14:23 volumio go-librespot[7833]: time="2025-12-19T21:14:23+08:00" level=info msg="generated new device id: 8b1a2631881abf371336525d390a0160285ad2e2" Dec 19 21:14:23 volumio go-librespot[7833]: time="2025-12-19T21:14:23+08:00" level=debug msg="stored credentials not found" Dec 19 21:14:24 volumio go-librespot[7833]: time="2025-12-19T21:14:24+08:00" level=debug msg="obtained new client token: AADujnP2ukJDe4mkSGQqonS0p5XycybIJ184zQiLbqokIWvXgdbzxgApaTmUdwka1iTAfRMKrG/NDxoL3VoG4GxoZ7190b6dPP9YYEXykJ2CKnSaLr5pE9+Igg5ZeZallazBynkPVmyYXWMEH75r5huiGN5TxhGyCQwngk6GtTdkKWcna9bj3RwaFgZQqAg5E0y7Kyt82hcC351GveikepqysiO/OopK0Dl9XHk3iraek1KLd8MgRor2VEWn7g==" Dec 19 21:14:24 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:25 volumio go-librespot[7833]: time="2025-12-19T21:14:25+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:14:26 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:26 volumio go-librespot[7833]: time="2025-12-19T21:14:26+08:00" level=debug msg="new websocket client" Dec 19 21:14:26 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:14:27 volumio go-librespot[7833]: time="2025-12-19T21:14:27+08:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.241.202:4070: connect: connection refused" Dec 19 21:14:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:27 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:29 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:29 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:29 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:14:29 volumio volumio[935]: (node:935) 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: 342) Dec 19 21:14:29 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:14:29 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:29 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:29 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:30 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:30 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:30 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 340. Dec 19 21:14:30 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:30 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:30 volumio go-librespot[7841]: Librespot-go daemon starting... Dec 19 21:14:30 volumio go-librespot[7841]: time="2025-12-19T21:14:30+08:00" level=info msg="generated new device id: 816c238d7b410c1803f38feab3a52e5d96df223e" Dec 19 21:14:30 volumio go-librespot[7841]: time="2025-12-19T21:14:30+08:00" level=debug msg="stored credentials not found" Dec 19 21:14:32 volumio volumio[935]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Dec 19 21:14:32 volumio volumio[935]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:106:19) Dec 19 21:14:32 volumio volumio[935]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:113:37) Dec 19 21:14:32 volumio volumio[935]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Dec 19 21:14:32 volumio volumio[935]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Dec 19 21:14:32 volumio volumio[935]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:144:13) Dec 19 21:14:32 volumio volumio[935]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:114:3) Dec 19 21:14:32 volumio volumio[935]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Dec 19 21:14:32 volumio volumio[935]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Dec 19 21:14:32 volumio volumio[935]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Dec 19 21:14:32 volumio volumio[935]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Dec 19 21:14:32 volumio volumio[935]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Dec 19 21:14:32 volumio volumio[935]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Dec 19 21:14:32 volumio volumio[935]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Dec 19 21:14:32 volumio volumio[935]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Dec 19 21:14:32 volumio volumio[935]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Dec 19 21:14:32 volumio go-librespot[7841]: time="2025-12-19T21:14:32+08:00" level=debug msg="obtained new client token: AADsGcGcxqnOt4fcgsPeDj/xNJZMAbO6s+TONgCVCmqF+dVKqtlCDyNo3n1r0sFwlwajK8u40UJV7M9tzbp7gry3Bnggao/51Kx/a81Spz1lOzRzi5ZI8XI4qiGIG04Qc3/YWot2cWfxJPLYP2QIYGM9/BquPbgvkRh0Jr0vRGm1VCw5+ezEdeHs6IgR32KKu4fd5BysOiYFDyeOSIkE/likbFeFK8O0250bnEo0MarjkATTF9cd7iNnEXKtSESo" Dec 19 21:14:33 volumio go-librespot[7841]: time="2025-12-19T21:14:33+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 19 21:14:33 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:33 volumio go-librespot[7841]: time="2025-12-19T21:14:33+08:00" level=debug msg="new websocket client" Dec 19 21:14:33 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:14:35 volumio go-librespot[7841]: time="2025-12-19T21:14:35+08:00" level=debug msg="completed keyexchange" Dec 19 21:14:36 volumio go-librespot[7841]: time="2025-12-19T21:14:36+08:00" level=debug msg="completed challenge" Dec 19 21:14:36 volumio go-librespot[7841]: time="2025-12-19T21:14:36+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:14:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:36 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:36 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:36 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:36 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:36 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:14:36 volumio volumio[935]: (node:935) 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: 344) Dec 19 21:14:36 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:14:36 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:36 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:36 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:39 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:39 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 341. Dec 19 21:14:39 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:39 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:39 volumio go-librespot[7850]: Librespot-go daemon starting... Dec 19 21:14:39 volumio go-librespot[7850]: time="2025-12-19T21:14:39+08:00" level=info msg="generated new device id: 6cb00c8c8042de4fa8a7c6f18980964bbdf858e5" Dec 19 21:14:39 volumio go-librespot[7850]: time="2025-12-19T21:14:39+08:00" level=debug msg="stored credentials not found" Dec 19 21:14:42 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:42 volumio go-librespot[7850]: time="2025-12-19T21:14:42+08:00" level=debug msg="new websocket client" Dec 19 21:14:42 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:14:42 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:43 volumio go-librespot[7850]: time="2025-12-19T21:14:43+08:00" level=debug msg="obtained new client token: AABy7rOGT6vx5ivXf209+XPTkP3y0Or8W1dxJ7y4fgU/LOA5sVSWY4Iw9J7TRUneFsPa5s2S4DlxBpO4uXOfM6alUbo/OqS3Pm61qRTxaQrRDBKeysgB2D+oxhA2HXObZEOMwBx5IvbqDY0MMfJ5q714kcbGo0FKLl8Wa85oRTF1IVQqUTjf2o+z/LTsgEByBTQYvbzK6D1gFuQaU0P+od4BlkfkFBsCdD82TZ4vnLn7eHcxU2MhFt5c2b2Ro8qD" Dec 19 21:14:44 volumio go-librespot[7850]: time="2025-12-19T21:14:44+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:14:45 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:45 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:14:45 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:45 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:45 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:46 volumio go-librespot[7850]: time="2025-12-19T21:14:46+08:00" level=debug msg="completed keyexchange" Dec 19 21:14:47 volumio go-librespot[7850]: time="2025-12-19T21:14:47+08:00" level=debug msg="completed challenge" Dec 19 21:14:47 volumio go-librespot[7850]: time="2025-12-19T21:14:47+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:14:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:47 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 19 21:14:47 volumio volumio[935]: at connResetException (internal/errors.js:607:14) Dec 19 21:14:47 volumio volumio[935]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 19 21:14:47 volumio volumio[935]: at Socket.emit (events.js:327:22) Dec 19 21:14:47 volumio volumio[935]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 19 21:14:47 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 19 21:14:47 volumio volumio[935]: (node:935) 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: 345) Dec 19 21:14:47 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:48 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:50 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:50 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 342. Dec 19 21:14:50 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:50 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:50 volumio go-librespot[7859]: Librespot-go daemon starting... Dec 19 21:14:50 volumio go-librespot[7859]: time="2025-12-19T21:14:50+08:00" level=info msg="generated new device id: e06cbcecffaf6eff89f4b9484a70c1dd2c49a371" Dec 19 21:14:50 volumio go-librespot[7859]: time="2025-12-19T21:14:50+08:00" level=debug msg="stored credentials not found" Dec 19 21:14:52 volumio go-librespot[7859]: time="2025-12-19T21:14:52+08:00" level=debug msg="obtained new client token: AADsIEVkWpUMsIBXvih04Te14YBQCzKjKGQAw5R9uoROAMM94gDma6hPIq6Wfq5b/N3/fWmZ1gS1ruTlhVnedl6RJZ6tuu3fCH7PpQBWJHpcDxv0rdZnDTJz5g+udjnCO6bRd3IdkA8ePY6P1/7ZjR9bfg7esZLlU7nC6E+JyEe37zzXOgv0faj2LVkZASk3+BQm9hgus4kl3KFQMI0E1C7TXGaPsWIz60tYwV62nziScuzJBQbPz1Zj/tLvC/AZ" Dec 19 21:14:53 volumio go-librespot[7859]: time="2025-12-19T21:14:53+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:14:53 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:53 volumio go-librespot[7859]: time="2025-12-19T21:14:53+08:00" level=debug msg="new websocket client" Dec 19 21:14:53 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:14:54 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:14:55 volumio go-librespot[7859]: time="2025-12-19T21:14:55+08:00" level=debug msg="completed keyexchange" Dec 19 21:14:56 volumio go-librespot[7859]: time="2025-12-19T21:14:56+08:00" level=debug msg="completed challenge" Dec 19 21:14:56 volumio go-librespot[7859]: time="2025-12-19T21:14:56+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:14:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:14:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:14:56 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:14:56 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:14:56 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:56 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:14:56 volumio volumio[935]: (node:935) 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: 346) Dec 19 21:14:56 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:14:56 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:14:56 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:14:56 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:14:59 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:14:59 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:14:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:14:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 343. Dec 19 21:14:59 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:14:59 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:14:59 volumio go-librespot[7912]: Librespot-go daemon starting... Dec 19 21:14:59 volumio go-librespot[7912]: time="2025-12-19T21:14:59+08:00" level=info msg="generated new device id: 4d455dfe9a0296b01c7b92030fc7cc7973ebf68a" Dec 19 21:14:59 volumio go-librespot[7912]: time="2025-12-19T21:14:59+08:00" level=debug msg="stored credentials not found" Dec 19 21:15:00 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:00 volumio go-librespot[7912]: time="2025-12-19T21:15:00+08:00" level=debug msg="obtained new client token: AADrScuQ49PFudb1qtJVPHq69bIZSiW+RbfQWeiy8c1WwvXsvuz0z6T6H4qul/RPFiNb9S5vlDxxof/uZsl6R6MkxOcGMAz7C+l4IB0hMgII6XDZ+MWVjDUrJT77Nge3IgloZkkrbg0H1eXK/tCk20cShF9PMNexohLe5FhlGfD9Z1uJb2nbo/hpIcvU4GOGF6jF4L2q40zy1DF+8bbTSS25rwEBzK/bLcKQGGDcy8tsBIj+wgAMZ/di2pSWPRt2" Dec 19 21:15:02 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:02 volumio go-librespot[7912]: time="2025-12-19T21:15:02+08:00" level=debug msg="new websocket client" Dec 19 21:15:02 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:15:02 volumio go-librespot[7912]: time="2025-12-19T21:15:02+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:15:03 volumio go-librespot[7912]: time="2025-12-19T21:15:03+08:00" level=debug msg="completed keyexchange" Dec 19 21:15:03 volumio go-librespot[7912]: time="2025-12-19T21:15:03+08:00" level=debug msg="completed challenge" Dec 19 21:15:03 volumio go-librespot[7912]: time="2025-12-19T21:15:03+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:15:03 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:15:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:15:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:15:05 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:15:05 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:05 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:15:05 volumio volumio[935]: (node:935) 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: 347) Dec 19 21:15:05 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:15:05 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:15:05 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:15:05 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:15:06 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:06 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:06 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:15:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 344. Dec 19 21:15:07 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:15:07 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:15:07 volumio go-librespot[7921]: Librespot-go daemon starting... Dec 19 21:15:07 volumio go-librespot[7921]: time="2025-12-19T21:15:07+08:00" level=info msg="generated new device id: ec9476745e0a269fb50b57eb631186a035f2e4d0" Dec 19 21:15:07 volumio go-librespot[7921]: time="2025-12-19T21:15:07+08:00" level=debug msg="stored credentials not found" Dec 19 21:15:09 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:09 volumio go-librespot[7921]: time="2025-12-19T21:15:09+08:00" level=debug msg="new websocket client" Dec 19 21:15:09 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:15:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 19 21:15:12 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:12 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:15:12 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:15:12 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:15:12 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:15:12 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:15:13 volumio go-librespot[7921]: time="2025-12-19T21:15:13+08:00" level=debug msg="obtained new client token: AADpm02KJiX3BpkLTXEdhekFn7RGyN/q7KITQ0tKIE7DNj26qaMNa7ZpxmXAAuNa65+WKt+YFXLy3OgKBjrcKWHEC1YJOXOTFZ7j/f8uGwNhTxdxL1yNmUm3omU+G5Ka/M2hiFlBZh0+Z9u9+eTceP09iztHxPf751KVKDbgXN0XcBMO5zA/3FeDDi96/FrL1hguGbLPkNs9uqjqGKO2jFT3/LlrDl7IZJgK5jvcQOXyo99M4p4xSugQvcMWXd2b" Dec 19 21:15:14 volumio go-librespot[7921]: time="2025-12-19T21:15:14+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:15:14 volumio go-librespot[7921]: time="2025-12-19T21:15:14+08:00" level=debug msg="completed keyexchange" Dec 19 21:15:15 volumio go-librespot[7921]: time="2025-12-19T21:15:15+08:00" level=debug msg="completed challenge" Dec 19 21:15:15 volumio go-librespot[7921]: time="2025-12-19T21:15:15+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:15:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:15:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:15:15 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 19 21:15:15 volumio volumio[935]: at connResetException (internal/errors.js:607:14) Dec 19 21:15:15 volumio volumio[935]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 19 21:15:15 volumio volumio[935]: at Socket.emit (events.js:327:22) Dec 19 21:15:15 volumio volumio[935]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 19 21:15:15 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 19 21:15:15 volumio volumio[935]: (node:935) 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: 348) Dec 19 21:15:15 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:15:18 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:18 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:15:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 345. Dec 19 21:15:18 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:15:18 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:18 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:15:18 volumio go-librespot[7931]: Librespot-go daemon starting... Dec 19 21:15:18 volumio go-librespot[7931]: time="2025-12-19T21:15:18+08:00" level=info msg="generated new device id: a411ae02c6230cc03e5a802ed7a8dbc69f0cf03e" Dec 19 21:15:18 volumio go-librespot[7931]: time="2025-12-19T21:15:18+08:00" level=debug msg="stored credentials not found" Dec 19 21:15:21 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:21 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:15:21 volumio go-librespot[7931]: time="2025-12-19T21:15:21+08:00" level=debug msg="new websocket client" Dec 19 21:15:21 volumio go-librespot[7931]: time="2025-12-19T21:15:21+08:00" level=debug msg="obtained new client token: AADRiuZI0nhswYHXJaxN2/EZTWQrPxLd2/s93clm0yEh7ZU7+5p7cGHyVYliDmxtvHnbC6UNbeaDULcUjeP0c96omdFuZ9RjDXdhfQUoGgcTcm6BMBiV84ll3HnaePyUkL9oLVwLqnf1WOsoXXyjDs3EBZXFXrrqieoHBVQ/0nVqYvmxGWHoxWT8i/03qOOJKLLPxpKrboRmoODuI3Y70YkRa5faV9WAZIjZxx5bjsSNpTHLxmSVRUdWjB5yngTC" Dec 19 21:15:22 volumio go-librespot[7931]: time="2025-12-19T21:15:22+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 19 21:15:23 volumio go-librespot[7931]: time="2025-12-19T21:15:23+08:00" level=debug msg="completed keyexchange" Dec 19 21:15:23 volumio go-librespot[7931]: time="2025-12-19T21:15:23+08:00" level=debug msg="completed challenge" Dec 19 21:15:23 volumio go-librespot[7931]: time="2025-12-19T21:15:23+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:15:23 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:15:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:15:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:15:24 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:15:24 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:24 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:15:24 volumio volumio[935]: (node:935) 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: 349) Dec 19 21:15:24 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:15:24 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:15:24 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:15:24 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:15:24 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:24 volumio kernel: hwmon hwmon1: Voltage normalised Dec 19 21:15:26 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:26 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:15:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 346. Dec 19 21:15:27 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:15:27 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:15:27 volumio go-librespot[7941]: Librespot-go daemon starting... Dec 19 21:15:27 volumio go-librespot[7941]: time="2025-12-19T21:15:27+08:00" level=info msg="generated new device id: cef15a0c7c3ba14398441b72b4a881f3c1f0ba3f" Dec 19 21:15:27 volumio go-librespot[7941]: time="2025-12-19T21:15:27+08:00" level=debug msg="stored credentials not found" Dec 19 21:15:28 volumio go-librespot[7941]: time="2025-12-19T21:15:28+08:00" level=debug msg="obtained new client token: AABKLy9iofjcrPveyfOhcD1M3mqY0lMPw1EPgwcbnmyBx6p7ztRuHm7z0HNPZEfHU7C/EfhXPmKbhGLMq/A9rtOWzhvVeODfACChQMdz6c123WOV9A3mnQ00dijQh/w7SrEuYSyR3ifAU3m/W2/DTLS35EhFVDQG1WEF6d3e62FRDENPnbQXs/ls6AmZjvboz2RaYVUvGiaT6yzU7YFO7qAvLiWof8SeY3FBYPGWLUk3zDmX4bf+3Rs96/w2lsMR" Dec 19 21:15:29 volumio go-librespot[7941]: time="2025-12-19T21:15:29+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 21:15:29 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:29 volumio go-librespot[7941]: time="2025-12-19T21:15:29+08:00" level=debug msg="new websocket client" Dec 19 21:15:29 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:15:30 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:31 volumio go-librespot[7941]: time="2025-12-19T21:15:31+08:00" level=debug msg="completed keyexchange" Dec 19 21:15:32 volumio go-librespot[7941]: time="2025-12-19T21:15:32+08:00" level=debug msg="completed challenge" Dec 19 21:15:32 volumio go-librespot[7941]: time="2025-12-19T21:15:32+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:15:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:15:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:15:32 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:15:32 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:15:32 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:32 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:15:32 volumio volumio[935]: (node:935) 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: 350) Dec 19 21:15:32 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:15:32 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:15:32 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:15:32 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:15:35 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:35 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:15:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 347. Dec 19 21:15:35 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:15:35 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:15:35 volumio go-librespot[7949]: Librespot-go daemon starting... Dec 19 21:15:35 volumio go-librespot[7949]: time="2025-12-19T21:15:35+08:00" level=info msg="generated new device id: 7228362c51f41a447c7cf245b637562d99de68c1" Dec 19 21:15:35 volumio go-librespot[7949]: time="2025-12-19T21:15:35+08:00" level=debug msg="stored credentials not found" Dec 19 21:15:36 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:36 volumio go-librespot[7949]: time="2025-12-19T21:15:36+08:00" level=debug msg="obtained new client token: AAC2OCFjVR5E2gC/i8RtPpLyqQV5Vr4g8AMr9x5TXMkCUUljuSG5zNhBmEcPHbhO7CUNu+kAFFlBgHhIOk5ipZZsZitczBU/dKBM0PcYOkl+Fns2/6JAWG0e7rL0IyHMlVS9QNG4yapDyCiUSW1BL0vOcQhuHzaMB1FcDJ68TIq1PM1Npn+bflmc5edgclMAuVmjZNWytfiNfq/5+k6G5z/buwowUJm/I3pnWQFFovLTV8tODtqat3FmovhqYw==" Dec 19 21:15:37 volumio go-librespot[7949]: time="2025-12-19T21:15:37+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 19 21:15:38 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:38 volumio go-librespot[7949]: time="2025-12-19T21:15:38+08:00" level=debug msg="new websocket client" Dec 19 21:15:38 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:15:40 volumio go-librespot[7949]: time="2025-12-19T21:15:40+08:00" level=debug msg="completed keyexchange" Dec 19 21:15:40 volumio go-librespot[7949]: time="2025-12-19T21:15:40+08:00" level=debug msg="completed challenge" Dec 19 21:15:40 volumio go-librespot[7949]: time="2025-12-19T21:15:40+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Dec 19 21:15:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 21:15:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 21:15:41 volumio volumio[935]: info: Connection to go-librespot Websocket closed Dec 19 21:15:41 volumio volumio[935]: info: Getting Spotify volume Dec 19 21:15:41 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:41 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 19 21:15:41 volumio volumio[935]: (node:935) 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: 351) Dec 19 21:15:41 volumio volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Dec 19 21:15:41 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Dec 19 21:15:41 volumio volumio[935]: info: CorePlayQueue::getTrack 108 Dec 19 21:15:41 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 19 21:15:42 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:44 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:44 volumio volumio[935]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 21:15:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 21:15:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 348. Dec 19 21:15:44 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 21:15:44 volumio systemd[1]: Started go-librespot Daemon. Dec 19 21:15:44 volumio go-librespot[7957]: Librespot-go daemon starting... Dec 19 21:15:44 volumio go-librespot[7957]: time="2025-12-19T21:15:44+08:00" level=info msg="generated new device id: 4bc13e90a97733e95d5695ab6704df2e3e9374e1" Dec 19 21:15:44 volumio go-librespot[7957]: time="2025-12-19T21:15:44+08:00" level=debug msg="stored credentials not found" Dec 19 21:15:45 volumio go-librespot[7957]: time="2025-12-19T21:15:45+08:00" level=debug msg="obtained new client token: AAAqPSTjbZONv+hrPPFcOn7UQSPsM7VBfb3QkvyRFNYc0T+/NFpMZCZP/jQbySauRiFEMTp1no4bQHZlEoDnPGdAPK3sD97UVcYJzT5QCIYihW9VlLuJ5wiHcav5zcGJOgmncfDnLALwLmEEHtOWffMUulOypUflxH+HLUy0RZGELtrRapV8fn342HwuPBX+kl/QoN7zKnWPzWd4qCbj3zxfMFE3DFOMFyYiOPu8pPqJka7UtjFvibA3Ptg=" Dec 19 21:15:46 volumio go-librespot[7957]: time="2025-12-19T21:15:46+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 19 21:15:47 volumio volumio[935]: info: Initializing connection to go-librespot Websocket Dec 19 21:15:47 volumio go-librespot[7957]: time="2025-12-19T21:15:47+08:00" level=debug msg="new websocket client" Dec 19 21:15:47 volumio volumio[935]: info: Connection to go-librespot Websocket established Dec 19 21:15:48 volumio volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 21:15:48 volumio volumio[935]: Error: connect ETIMEDOUT 31.13.96.194:443 Dec 19 21:15:48 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 19 21:15:48 volumio volumio[935]: errno: -110, Dec 19 21:15:48 volumio volumio[935]: code: 'ETIMEDOUT', Dec 19 21:15:48 volumio volumio[935]: syscall: 'connect', Dec 19 21:15:48 volumio volumio[935]: address: '31.13.96.194', Dec 19 21:15:48 volumio volumio[935]: port: 443 Dec 19 21:15:48 volumio volumio[935]: } Dec 19 21:15:48 volumio volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 21:15:48 volumio wpa_supplicant[778]: wlan0: Failed to initiate sched scan Dec 19 21:15:48 volumio sudo[7976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-19 21:14 Dec 19 21:15:48 volumio sudo[7976]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"