-- Logs begin at Wed 2026-01-28 20:11:46 CST, end at Wed 2026-01-28 22:47:27 CST. -- Jan 28 22:46:01 volumio go-librespot[8217]: time="2026-01-28T22:46:01+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" Jan 28 22:46:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:01 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:02 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:02 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:02 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:46:02 volumio volumio[940]: (node:940) 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: 3981) Jan 28 22:46:02 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jan 28 22:46:02 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:02 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:02 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:03 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:04 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:04 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4410. Jan 28 22:46:04 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:04 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:04 volumio go-librespot[8226]: Librespot-go daemon starting... Jan 28 22:46:04 volumio go-librespot[8226]: time="2026-01-28T22:46:04+08:00" level=info msg="generated new device id: 11bff050b2a46471d02099b42a7c9fa63aca31a7" Jan 28 22:46:04 volumio go-librespot[8226]: time="2026-01-28T22:46:04+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:05 volumio go-librespot[8226]: time="2026-01-28T22:46:05+08:00" level=debug msg="obtained new client token: AACN2vbykP/uhn4F3HVJYO5navYd9QrI7ht50VUyHrKW+qOkXjs/0CaCueqEHl2Yh7s3Jl/209N8LL918PS+VPjqmOlt/7TweKt8ZwugLOEohULoKSlm/60wurLTODpahvgmt3USZkUT7pWyyTFXpZze3/q4ZTy2v3BQ2/ptzC9qxeQg5fAuZFbp9J3XFNbBd6Ta1kEEwa6KG6x5BIoEN5pGUvp3Qzawj+9sh4B/aeHbWOT+x8ujJLza/HTaOX1tPQ==" Jan 28 22:46:06 volumio go-librespot[8226]: time="2026-01-28T22:46:06+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]" Jan 28 22:46:06 volumio go-librespot[8226]: time="2026-01-28T22:46:06+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 22:46:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:07 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:07 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:09 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4411. Jan 28 22:46:09 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:10 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:10 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:10 volumio go-librespot[8235]: Librespot-go daemon starting... Jan 28 22:46:10 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:10 volumio go-librespot[8235]: time="2026-01-28T22:46:10+08:00" level=info msg="generated new device id: d74707b18362c3c66ef934d271e7bae6f62d91f2" Jan 28 22:46:10 volumio go-librespot[8235]: time="2026-01-28T22:46:10+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:11 volumio go-librespot[8235]: time="2026-01-28T22:46:11+08:00" level=debug msg="obtained new client token: AAAF9u9U374ubkn1UB8jG9d8HmmZkeJbYD2r6//lZ54GfCKmN2s8z6W9zAaaDP3pipTHppd+6CIQcX7yJsjmJkV3aj3KZ2vCRcuEgld/3PJmPHMJmMFto6yM0a7dl/yCYmW7jkZFhO228CKvlJsYfHOyES/4XNhRe3k1iW9qZAAwc9to+IGPUJpBGfotzb7hCRR1UlXzwq8ZQswJbPtG0+XvEC9RLAaAR4ZwT6qsdrCKEMyqklpFvqE6VpQ9AfZdwQ==" Jan 28 22:46:12 volumio go-librespot[8235]: time="2026-01-28T22:46:12+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]" Jan 28 22:46:12 volumio go-librespot[8235]: time="2026-01-28T22:46:12+08:00" level=debug msg="completed keyexchange" Jan 28 22:46:13 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:13 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:46:13 volumio go-librespot[8235]: time="2026-01-28T22:46:13+08:00" level=debug msg="new websocket client" Jan 28 22:46:13 volumio go-librespot[8235]: time="2026-01-28T22:46:13+08:00" level=debug msg="completed challenge" Jan 28 22:46:13 volumio go-librespot[8235]: time="2026-01-28T22:46:13+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" Jan 28 22:46:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:13 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:15 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:16 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:16 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:16 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:46:16 volumio volumio[940]: (node:940) 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: 3982) Jan 28 22:46:16 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jan 28 22:46:16 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:16 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:16 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:16 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:16 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4412. Jan 28 22:46:16 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:16 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:16 volumio go-librespot[8243]: Librespot-go daemon starting... Jan 28 22:46:16 volumio go-librespot[8243]: time="2026-01-28T22:46:16+08:00" level=info msg="generated new device id: 6a2bd41fe24f77b840c076d4f7aa5b1c5d804613" Jan 28 22:46:16 volumio go-librespot[8243]: time="2026-01-28T22:46:16+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:17 volumio go-librespot[8243]: time="2026-01-28T22:46:17+08:00" level=debug msg="obtained new client token: AADNgyYs9Y+qoNBmMOW6O8Ul6mKyLu6rc/Wd5V2FihSTznP4TK+Dvpigrdn0OPPBgvwDSrTWyWcAvpBlJD7LfAnZL/l40/k1Sbob2kHkMNQp3udU/1Gpc4T3ZruisGVEXgxAincOgl74JYoBTNz+I7qRkNwAFxDQs3GLDQqS2WWKrTB/kYZkPNWuMrIcXMFRYb4cJ9Kr5idacFDCz4lTgA0aA3nTtKUf31IPl2reAmdn7CfldRErwFwd7KUHM1QpZw==" Jan 28 22:46:18 volumio go-librespot[8243]: time="2026-01-28T22:46:18+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]" Jan 28 22:46:19 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:19 volumio go-librespot[8243]: time="2026-01-28T22:46:19+08:00" level=debug msg="new websocket client" Jan 28 22:46:19 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:46:20 volumio go-librespot[8243]: time="2026-01-28T22:46:20+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 22:46:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:20 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:21 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:22 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:22 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:22 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:46:22 volumio volumio[940]: (node:940) 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: 3983) Jan 28 22:46:22 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:46:22 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:22 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:22 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:23 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:23 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4413. Jan 28 22:46:24 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:24 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:24 volumio go-librespot[8252]: Librespot-go daemon starting... Jan 28 22:46:24 volumio go-librespot[8252]: time="2026-01-28T22:46:24+08:00" level=info msg="generated new device id: bd4c7a10eea7866f2686760faa65ea9811c465f2" Jan 28 22:46:24 volumio go-librespot[8252]: time="2026-01-28T22:46:24+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:26 volumio go-librespot[8252]: time="2026-01-28T22:46:26+08:00" level=debug msg="obtained new client token: AACFd8X8t6lornKGhNr8syuw/AikMkdq3jjqtT2NERgbeNKmEeueNeudh9gFZR0J4A2zArwR63/UytDUx7UzoU5daZFRkVse1vJRtYseOXXndl0a5KtMyO/eX/5xEaeiF0UrnUbXqmLr9yvQf4J8yaXzRwnURln2V7YLAi63YcQzrXjVjsEx0/txLOaDswW3v5MdhOBzPvJuDkmYkvgNCyqcWmpz2LdRNcjZ0qb+R3qvsMdAWKXg1szrydmfWi9TCA==" Jan 28 22:46:26 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:27 volumio go-librespot[8252]: time="2026-01-28T22:46:27+08:00" level=debug msg="new websocket client" Jan 28 22:46:27 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:46:27 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:27 volumio go-librespot[8252]: time="2026-01-28T22:46:27+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]" Jan 28 22:46:29 volumio go-librespot[8252]: time="2026-01-28T22:46:29+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" Jan 28 22:46:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:29 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:30 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:30 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:30 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:46:30 volumio volumio[940]: (node:940) 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: 3984) Jan 28 22:46:30 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:46:30 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:30 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:30 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:31 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9B%B2%E8%82%96%E5%86%B0/%E5%8E%9A%E9%A2%9C%E6%97%A0%E8%80%BB/e3f6d265-c06f-48b7-8957-4a1db074a3ef.jpg' Jan 28 22:46:31 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E6%98%95%E8%9E%8D/%E5%90%AC%E6%88%91%E8%AF%B4%E8%B0%A2%E8%B0%A2%E4%BD%A0/87b5b845-70fa-4b94-b146-2cd941c0091c.jpg' Jan 28 22:46:31 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E4%BC%A6/%E6%B8%B8%E5%B1%B1%E6%81%8B/0f6932f0-4da4-42fe-87ee-be6236fd5129.jpg' Jan 28 22:46:31 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8/%E4%B8%8D%E8%BF%87%E4%BA%BA%E9%97%B4/3f4ef07f-d08f-4bb3-88e7-3a41a44df44b.jpg' Jan 28 22:46:31 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8/%E7%82%B9%E6%AD%8C%E7%9A%84%E4%BA%BA/3eec541f-6d00-408c-9b19-4aeb11bfa8c3.jpg' Jan 28 22:46:32 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:32 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4414. Jan 28 22:46:32 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:32 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:32 volumio go-librespot[8302]: Librespot-go daemon starting... Jan 28 22:46:32 volumio go-librespot[8302]: time="2026-01-28T22:46:32+08:00" level=info msg="generated new device id: 7e6f017df1a33f1bd9049441f975c0240f6e1490" Jan 28 22:46:32 volumio go-librespot[8302]: time="2026-01-28T22:46:32+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:33 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:33 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%92%8B%E9%9B%AA%E5%84%BF/%E8%8E%AB%E9%97%AE%E5%BD%92%E6%9C%9F/61cf8802-58b1-490b-bde4-9cfc8691287b.jpg' Jan 28 22:46:33 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A6%81%E4%B8%8D%E8%A6%81%E4%B9%B0%E8%8F%9C/%E4%B8%8B%E5%B1%B1/3335c47b-c5c7-4923-b283-a4a63b43b007.jpg' Jan 28 22:46:33 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BF%E6%82%A0%E6%82%A0/%E6%97%A7%E6%A2%A6%E4%B8%80%E5%9C%BA/0323ca6d-9abb-43c1-a7e1-479e6db68179.jpg' Jan 28 22:46:33 volumio go-librespot[8302]: time="2026-01-28T22:46:33+08:00" level=debug msg="obtained new client token: AACMwDYiWYkACelmgteHnH1ojkUYXWGdUUZWu5O2ouX3+seA0q8uqpYB00GSiV4blfd/zek47yi5nOc191znYqQlBv33smCV5yOf8z7dMWjBibfTt8awZxP3zzLp+E6NzDlAc2zN0oDz8BoUXSrNYZQMqbJ9+cKC6iwlGGl2vo55w50uZWFDwtloA0RV19SEzdgz8V7ljstTn+Y0IYzNn59uEaBsIDLc9FDEYoWFja08XYYovNh0vkgbWaPTzesmJA==" Jan 28 22:46:35 volumio go-librespot[8302]: time="2026-01-28T22:46:35+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]" Jan 28 22:46:35 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:35 volumio go-librespot[8302]: time="2026-01-28T22:46:35+08:00" level=debug msg="new websocket client" Jan 28 22:46:35 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:46:37 volumio go-librespot[8302]: time="2026-01-28T22:46:37+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" Jan 28 22:46:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:37 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:38 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BB%BB%E7%84%B6/%E9%A3%9E%E9%B8%9F%E5%92%8C%E8%9D%89/417c264d-75f3-45ce-96e7-29b9abafff95.jpg' Jan 28 22:46:38 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8A%B1%E5%83%AE/%E7%AC%91%E7%BA%B3/1d5c5fe0-1878-4cac-8408-6fcabd87cb9f.jpg' Jan 28 22:46:38 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9B%B6%E4%B8%80%E4%B9%9D%E9%9B%B6%E8%B4%B0/%E5%BF%98%E5%B7%9D%E5%BD%BC%E5%B2%B8/604d5be6-ca2b-4778-8d1c-87c4cca34d67.jpg' Jan 28 22:46:38 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:38 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:38 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:46:38 volumio volumio[940]: (node:940) 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: 3985) Jan 28 22:46:38 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:46:38 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:38 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:38 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:39 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:39 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9F%8F%E6%9D%BE/%E5%90%AC%E9%97%BB%E4%BD%99%E7%94%9F/98eb5bd0-723d-4f02-a82c-df7dfcab39d3.jpg' Jan 28 22:46:39 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 28 22:46:40 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:40 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4415. Jan 28 22:46:40 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:40 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:40 volumio go-librespot[8313]: Librespot-go daemon starting... Jan 28 22:46:40 volumio go-librespot[8313]: time="2026-01-28T22:46:40+08:00" level=info msg="generated new device id: 63f3079b34fb07a62a13d1d46aeb940f050a3e3a" Jan 28 22:46:40 volumio go-librespot[8313]: time="2026-01-28T22:46:40+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:41 volumio go-librespot[8313]: time="2026-01-28T22:46:41+08:00" level=debug msg="obtained new client token: AACr3Ib4jC++amR5W7dvzhagxFvKcRnhKXOb5jl7RzbRLa4h3wBU3ObhgdPVjqrRu0wRsW/aCGQufeeJcc7B0Nlj5WT0UuYZTYDf/YkLKupHHCjF5/ueJrbTnHWUYtwmoddoFN8jQswO2q2JV7UKedhCKUZRQLPYW27NGTyLZC8NyskKMepYH/ghkSGCpsYZNZ7p/2EeF8WsWs9E18u44krLE+Hl/89j8ijcAAd4lG95wQx+46BhOPu3xS2aw1qN2w==" Jan 28 22:46:43 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:43 volumio go-librespot[8313]: time="2026-01-28T22:46:43+08:00" level=debug msg="new websocket client" Jan 28 22:46:43 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:46:43 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AD%9F%E9%A2%96/%E4%B8%80%E4%B8%AA%E4%BA%BA%E6%8C%BA%E5%A5%BD/a200e857-91d8-44c6-aa4d-fbfc3f80b845.jpg' Jan 28 22:46:44 volumio kernel: hwmon hwmon1: Voltage normalised Jan 28 22:46:44 volumio go-librespot[8313]: time="2026-01-28T22:46: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]" Jan 28 22:46:45 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:46 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:46 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:46:46 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:46 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:46 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:46 volumio go-librespot[8313]: time="2026-01-28T22:46:46+08:00" level=debug msg="completed keyexchange" Jan 28 22:46:47 volumio go-librespot[8313]: time="2026-01-28T22:46:47+08:00" level=debug msg="completed challenge" Jan 28 22:46:47 volumio go-librespot[8313]: time="2026-01-28T22:46: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" Jan 28 22:46:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:47 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: socket hang up Jan 28 22:46:47 volumio volumio[940]: at connResetException (internal/errors.js:607:14) Jan 28 22:46:47 volumio volumio[940]: at Socket.socketOnEnd (_http_client.js:493:23) Jan 28 22:46:47 volumio volumio[940]: at Socket.emit (events.js:327:22) Jan 28 22:46:47 volumio volumio[940]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 28 22:46:47 volumio volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 28 22:46:47 volumio volumio[940]: (node:940) 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: 3986) Jan 28 22:46:47 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:47 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BF%E5%86%97/%E4%BD%A0%E7%9A%84%E7%AD%94%E6%A1%88/2998688e-29ba-4473-993d-2c76866a692d.jpg' Jan 28 22:46:47 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E8%8D%A3%E6%B5%A9/%E9%BA%BB%E9%9B%80/cd629bed-dbc7-47af-99aa-aeaf61bc280b.jpg' Jan 28 22:46:47 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%AD%8F%E6%96%B0%E9%9B%A8/%E7%99%BE%E8%8A%B1%E9%A6%99/165be284-4881-4e47-9416-23eefd9b4add.jpg' Jan 28 22:46:50 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E7%90%AA/%E9%80%81%E4%BA%B2/6c68ccac-437e-4fbe-82f8-0831e1102967.jpg' Jan 28 22:46:50 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/G.E.M.%E9%82%93%E7%B4%AB%E6%A3%8B/%E6%91%A9%E5%A4%A9%E5%8A%A8%E7%89%A9%E5%9B%AD/d4c3e4f4-8a62-4a40-9222-7d2554651b5c.jpg' Jan 28 22:46:50 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:50 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:50 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B0%B4%E6%BA%90%E7%BF%BB%E5%AD%90/%E6%88%91%E5%8F%88%E5%88%9D%E6%81%8B%E4%BA%86/6e93a4dc-d109-4b31-82d5-24ad8df0c2fa.jpg' Jan 28 22:46:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4416. Jan 28 22:46:50 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:50 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:50 volumio go-librespot[8323]: Librespot-go daemon starting... Jan 28 22:46:50 volumio go-librespot[8323]: time="2026-01-28T22:46:50+08:00" level=info msg="generated new device id: 96684fd1e0aeeabf82fca90caf14c4cf004b91d1" Jan 28 22:46:50 volumio go-librespot[8323]: time="2026-01-28T22:46:50+08:00" level=debug msg="stored credentials not found" Jan 28 22:46:51 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:51 volumio go-librespot[8323]: time="2026-01-28T22:46:51+08:00" level=debug msg="obtained new client token: AAAtytydGuAxWdoXUCY0NRAe3NyRU0N2MMiKhQggWJ+1e8Rzpqi/sQtEjmjjB/etLQ0YUkdFNFdNHoVM+sQ/TMJuCH+7bbyUY1pG98q0S8low8d05N3fuWk0M2CwEDJAyzfbpvzErdDdskXD7whyKbp/hgISkUOKJ9WHukiL0IIQrIIC/CnHzxLQNRKd5oxQKzfTQ7kblibhHLYgjnMYCBmk1DiMb4whZUTAmAv9AeyMfshoxzRd6M6ErZLZAHg=" Jan 28 22:46:53 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:53 volumio go-librespot[8323]: time="2026-01-28T22:46:53+08:00" level=debug msg="new websocket client" Jan 28 22:46:53 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:46:53 volumio go-librespot[8323]: time="2026-01-28T22:46: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 28 22:46:55 volumio go-librespot[8323]: time="2026-01-28T22:46:55+08:00" level=debug msg="completed keyexchange" Jan 28 22:46:55 volumio go-librespot[8323]: time="2026-01-28T22:46:55+08:00" level=debug msg="completed challenge" Jan 28 22:46:55 volumio go-librespot[8323]: time="2026-01-28T22:46:55+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" Jan 28 22:46:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:46:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:46:55 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:46:56 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:46:56 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:56 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:46:56 volumio volumio[940]: (node:940) 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: 3987) Jan 28 22:46:56 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:46:56 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:46:56 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:46:56 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:46:57 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:46:58 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:46:58 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:46:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:46:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4417. Jan 28 22:46:58 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:46:59 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:46:59 volumio go-librespot[8331]: Librespot-go daemon starting... Jan 28 22:46:59 volumio go-librespot[8331]: time="2026-01-28T22:46:59+08:00" level=info msg="generated new device id: 3a3dc7e2e7fb37aca29fc8fa2dc719ee4590f5b4" Jan 28 22:46:59 volumio go-librespot[8331]: time="2026-01-28T22:46:59+08:00" level=debug msg="stored credentials not found" Jan 28 22:47:00 volumio go-librespot[8331]: time="2026-01-28T22:47:00+08:00" level=debug msg="obtained new client token: AAC1ixMC4SCEEcPaa9lWV9lVARuwJsdsEdjBoUbJYKW6SfNG3yDQSWCyQqRio+F5QHkSYP0B6o+UNajcuZP/d6ec0CtsLhOnWCoS+fEWDDOZghRwyViWsOv/T4JdbCY+wVPQoSCTBXQWDX1s2b6n0DcJRNIyok9vgHmgJ2ba1VY3C3Rvclrq+cVDgGPAhDb5jKcKoxMW0ZZFhpe0a0HF+pVGIhn2WXM2rjSqYQYUmb3erPe+mgC21msVcCoAerUZqA==" Jan 28 22:47:01 volumio go-librespot[8331]: time="2026-01-28T22:47: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]" Jan 28 22:47:01 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:47:01 volumio go-librespot[8331]: time="2026-01-28T22:47:01+08:00" level=debug msg="new websocket client" Jan 28 22:47:01 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:47:03 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:47:03 volumio go-librespot[8331]: time="2026-01-28T22:47:03+08:00" level=debug msg="completed keyexchange" Jan 28 22:47:04 volumio go-librespot[8331]: time="2026-01-28T22:47:04+08:00" level=debug msg="completed challenge" Jan 28 22:47:04 volumio go-librespot[8331]: time="2026-01-28T22:47:04+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" Jan 28 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:47:04 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:47:04 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:47:04 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:47:04 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 28 22:47:04 volumio volumio[940]: (node:940) 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: 3988) Jan 28 22:47:04 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:47:04 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:47:04 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:47:04 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:47:07 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:47:07 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:47:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4418. Jan 28 22:47:07 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:47:07 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:47:07 volumio go-librespot[8344]: Librespot-go daemon starting... Jan 28 22:47:07 volumio go-librespot[8344]: time="2026-01-28T22:47:07+08:00" level=info msg="generated new device id: c001b42748d47cc34db998d449e5f37b769be03c" Jan 28 22:47:07 volumio go-librespot[8344]: time="2026-01-28T22:47:07+08:00" level=debug msg="stored credentials not found" Jan 28 22:47:08 volumio go-librespot[8344]: time="2026-01-28T22:47:08+08:00" level=debug msg="obtained new client token: AADqjK2HTphcB0qlbiod2JbCPTTtnTFD9XKP25Gck9/ZWrcevMsXq9ll5bNY5dxFlzn5GexWbdSoj6Tjzpjbm7sB3UtRUqG7deSjXh6AqP+HFKHvXnSdisvzPIvhjDF7ckhftgmukQaGTy8729Pzkol9sTwjQxC4FXO2zso3q3rIGh5V5shMn/jspH+3Rw9vmr5uHJhwG4RfbD77IC/NKR3ahtqCXf7APmU2VoF80R/31RoKAgxV/OJdDG2wevX90g==" Jan 28 22:47:08 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:47:10 volumio go-librespot[8344]: time="2026-01-28T22:47:10+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]" Jan 28 22:47:10 volumio go-librespot[8344]: time="2026-01-28T22:47:10+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 22:47:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:47:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:47:10 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:47:10 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:47:13 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:47:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:47:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4419. Jan 28 22:47:13 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:47:13 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:47:13 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:47:13 volumio go-librespot[8353]: Librespot-go daemon starting... Jan 28 22:47:13 volumio go-librespot[8353]: time="2026-01-28T22:47:13+08:00" level=info msg="generated new device id: f847767d1923eac533764254907f614f7b51eda6" Jan 28 22:47:13 volumio go-librespot[8353]: time="2026-01-28T22:47:13+08:00" level=debug msg="stored credentials not found" Jan 28 22:47:14 volumio go-librespot[8353]: time="2026-01-28T22:47:14+08:00" level=debug msg="obtained new client token: AABsmvFJWydtD43/FyCTJ1eKJNMkMUYSaWfteUedW9uL/0X6/Zr9dfLcDySrA4pkVS2LYqo53Cxhz2T66gF2m/yw2jieVvthUulX2dKVIKmKq/+CeYS0TsuCFer27hNt1QihfYWCHaui5M3gMx/NxdjU1E0o8p3adrgnHf4U702KdL9Z2Em2NJ0qqCTkW4S9sTW9OfwnHNy4JRRwL2/zh+zodE3/LbInWRtF8rauTIa9p+jOfJ2eI2g2w7gpUFz1pQ==" Jan 28 22:47:14 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:47:15 volumio go-librespot[8353]: time="2026-01-28T22:47:15+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]" Jan 28 22:47:15 volumio go-librespot[8353]: time="2026-01-28T22:47:15+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 22:47:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:47:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:47:16 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:47:16 volumio volumio[940]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 22:47:17 volumio volumio[940]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E5%BF%83%E5%87%8C/CYNDILOVES2SING%20%E7%88%B1%E3%80%82%E5%BF%83%E5%87%8C/7d52080c-842a-4745-8503-b402f1663daa.jpg' Jan 28 22:47:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 28 22:47:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4420. Jan 28 22:47:18 volumio systemd[1]: Stopped go-librespot Daemon. Jan 28 22:47:19 volumio systemd[1]: Started go-librespot Daemon. Jan 28 22:47:19 volumio go-librespot[8362]: Librespot-go daemon starting... Jan 28 22:47:19 volumio go-librespot[8362]: time="2026-01-28T22:47:19+08:00" level=info msg="generated new device id: f061a7f6105e57a9e332c3514d4a42d651a44531" Jan 28 22:47:19 volumio go-librespot[8362]: time="2026-01-28T22:47:19+08:00" level=debug msg="stored credentials not found" Jan 28 22:47:19 volumio volumio[940]: info: Initializing connection to go-librespot Websocket Jan 28 22:47:19 volumio volumio[940]: info: Connection to go-librespot Websocket established Jan 28 22:47:19 volumio go-librespot[8362]: time="2026-01-28T22:47:19+08:00" level=debug msg="new websocket client" Jan 28 22:47:20 volumio go-librespot[8362]: time="2026-01-28T22:47:20+08:00" level=debug msg="obtained new client token: AABsZec/fJiueFIpVAIGGp2X8Xk6yMdOrSYYxm+HLz4A3GJEujF28cPHRKXdNLu43SHKuTaWPslH/Ztp8X4HcNtDktV8E5JzuQNa1gv9VEb7BpGtM1XLgKRVx1trJNmoCxAQLK8AZGn+njZcFB6c+pPDmpr4siFQsO2+T1LH8VooKsCDKYUsgNBPtEJar8q2FrhNCo8YTYREB7g1rYh1SpWgMgWYrbdFXCu7QN8BVzHzjB9OAUenfkKTd4VVu6U=" Jan 28 22:47:20 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:47:21 volumio go-librespot[8362]: time="2026-01-28T22:47:21+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]" Jan 28 22:47:22 volumio volumio[940]: info: Getting Spotify volume Jan 28 22:47:22 volumio volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jan 28 22:47:22 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 28 22:47:22 volumio volumio[940]: info: CorePlayQueue::getTrack 130 Jan 28 22:47:22 volumio volumio[940]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jan 28 22:47:23 volumio go-librespot[8362]: time="2026-01-28T22:47:23+08:00" level=debug msg="completed keyexchange" Jan 28 22:47:24 volumio go-librespot[8362]: time="2026-01-28T22:47:24+08:00" level=debug msg="completed challenge" Jan 28 22:47:24 volumio go-librespot[8362]: time="2026-01-28T22:47:24+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" Jan 28 22:47:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:47:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 22:47:24 volumio volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: socket hang up Jan 28 22:47:24 volumio volumio[940]: at connResetException (internal/errors.js:607:14) Jan 28 22:47:24 volumio volumio[940]: at Socket.socketOnEnd (_http_client.js:493:23) Jan 28 22:47:24 volumio volumio[940]: at Socket.emit (events.js:327:22) Jan 28 22:47:24 volumio volumio[940]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 28 22:47:24 volumio volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 28 22:47:24 volumio volumio[940]: (node:940) 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: 3989) Jan 28 22:47:24 volumio volumio[940]: info: Connection to go-librespot Websocket closed Jan 28 22:47:26 volumio volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:47:26 volumio volumio[940]: Error: connect ECONNREFUSED 31.13.69.245:443 Jan 28 22:47:26 volumio volumio[940]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 28 22:47:26 volumio volumio[940]: errno: -111, Jan 28 22:47:26 volumio volumio[940]: code: 'ECONNREFUSED', Jan 28 22:47:26 volumio volumio[940]: syscall: 'connect', Jan 28 22:47:26 volumio volumio[940]: address: '31.13.69.245', Jan 28 22:47:26 volumio volumio[940]: port: 443 Jan 28 22:47:26 volumio volumio[940]: } Jan 28 22:47:26 volumio volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 22:47:26 volumio wpa_supplicant[776]: wlan0: Failed to initiate sched scan Jan 28 22:47:27 volumio sudo[8421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 22:46 Jan 28 22:47:27 volumio sudo[8421]: 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"