-- Logs begin at Mon 2026-04-13 20:25:08 CST, end at Mon 2026-04-13 22:24:24 CST. -- Apr 13 22:23:00 volumio go-librespot[16711]: time="2026-04-13T22:23:00+08:00" level=debug msg="obtained new client token: AAAkIz84E7ADv2cywrYC+UaOOn2l1bO8BqjWYs33TrFSF1Zfy3jtmCWtsf7L7pJaufFiVjIcnJNgELf4rdXsD0cVNF78RJowWr4oKsJmlmkY/476omvI/bhsMaSXeqDV5QGDxViD/PnTExwW9f2JwtKHSM9hCavZrry/BJJb4M5VrAdS8WAMvIDXQGvJVjlVSU3dn18ZGm7hx02fEcxQqDgXh51psw12WQ8Gx3Cp8WhsLVGaWM4PpdvWGvaYW88=" Apr 13 22:23:01 volumio volumio[946]: info: Getting Spotify volume Apr 13 22:23:01 volumio volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 13 22:23:01 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Apr 13 22:23:01 volumio volumio[946]: info: CorePlayQueue::getTrack 143 Apr 13 22:23:01 volumio go-librespot[16711]: time="2026-04-13T22:23: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 13 22:23:03 volumio go-librespot[16711]: time="2026-04-13T22:23:03+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" Apr 13 22:23:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:03 volumio volumio[946]: (node:946) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 13 22:23:03 volumio volumio[946]: at connResetException (internal/errors.js:607:14) Apr 13 22:23:03 volumio volumio[946]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 13 22:23:03 volumio volumio[946]: at Socket.emit (events.js:327:22) Apr 13 22:23:03 volumio volumio[946]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 13 22:23:03 volumio volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 13 22:23:03 volumio volumio[946]: (node:946) 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: 732) Apr 13 22:23:03 volumio volumio[946]: info: Connection to go-librespot Websocket closed Apr 13 22:23:03 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:04 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:23:06 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:06 volumio volumio[946]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 728. Apr 13 22:23:06 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:06 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:06 volumio go-librespot[16763]: Librespot-go daemon starting... Apr 13 22:23:06 volumio go-librespot[16763]: time="2026-04-13T22:23:06+08:00" level=info msg="generated new device id: b79068682ad3de1764f25f17188f0f691863bcd9" Apr 13 22:23:06 volumio go-librespot[16763]: time="2026-04-13T22:23:06+08:00" level=debug msg="stored credentials not found" Apr 13 22:23:08 volumio go-librespot[16763]: time="2026-04-13T22:23:08+08:00" level=debug msg="obtained new client token: AABCuSSwPLPVDHRVPyNd8bIvKyigoLQR0l4ZnLwM3+sqyup6xRtsWO2xZdVVdl4NlB9izLADLj8Q+fqGJfIHYm+bi895mng+aQUT1AprLFWlOawI8IgKO0qdrBYLps/WJfoj5tEd5a3HDXnW96wHP4gGm/FgslPI5PN9f5ghM+W54Q/SXq34P4O4zdKQk/K9fIxbZsk3R8dzjzJDhEqQF+sHN2d4kdUnr1UtmH2Tx6Pc3oz+4VIo9X2bV4xAsUeXnA==" Apr 13 22:23:09 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:09 volumio go-librespot[16763]: time="2026-04-13T22:23:09+08:00" level=debug msg="new websocket client" Apr 13 22:23:09 volumio volumio[946]: info: Connection to go-librespot Websocket established Apr 13 22:23:09 volumio go-librespot[16763]: time="2026-04-13T22:23:09+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]" Apr 13 22:23:09 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:11 volumio go-librespot[16763]: time="2026-04-13T22:23:11+08:00" level=debug msg="completed keyexchange" Apr 13 22:23:12 volumio go-librespot[16763]: time="2026-04-13T22:23:12+08:00" level=debug msg="completed challenge" Apr 13 22:23:12 volumio go-librespot[16763]: time="2026-04-13T22:23:12+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" Apr 13 22:23:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:12 volumio volumio[946]: info: Connection to go-librespot Websocket closed Apr 13 22:23:12 volumio volumio[946]: info: Getting Spotify volume Apr 13 22:23:12 volumio volumio[946]: (node:946) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:12 volumio volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 13 22:23:12 volumio volumio[946]: (node:946) 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: 733) Apr 13 22:23:12 volumio volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 13 22:23:12 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Apr 13 22:23:12 volumio volumio[946]: info: CorePlayQueue::getTrack 143 Apr 13 22:23:14 volumio kernel: hwmon hwmon1: Voltage normalised Apr 13 22:23:15 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:15 volumio volumio[946]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 729. Apr 13 22:23:15 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:15 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:15 volumio go-librespot[16777]: Librespot-go daemon starting... Apr 13 22:23:15 volumio go-librespot[16777]: time="2026-04-13T22:23:15+08:00" level=info msg="generated new device id: 7eb649fd523d51d5c6406a07d2c6483049a2f910" Apr 13 22:23:15 volumio go-librespot[16777]: time="2026-04-13T22:23:15+08:00" level=debug msg="stored credentials not found" Apr 13 22:23:15 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:16 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:23:17 volumio go-librespot[16777]: time="2026-04-13T22:23:17+08:00" level=debug msg="obtained new client token: AADaluWqhGm//BBVE2ijM44HsjW/wTMxPzVuJK1hkM7rm5JM6SmTJBA3U0htoNq2YBzLlVP7+S8r5VbJGxO/sbEmNGfH+Di9rd0Y7tuBe2tULBwdr0OZE8fGexcnQW1O12SMXGC2HuGVItT9TnNBs4byoDgYx4h1ddP/Yog2Gb2izSGDdHMD8HwjayJ6qKXBJleIQzxon/OIxe0IpqzRo7hHqugSw8mZN5VS487TP6/aBLfjVE92RcqAC8l5JZkfiQ==" Apr 13 22:23:18 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:18 volumio go-librespot[16777]: time="2026-04-13T22:23:18+08:00" level=debug msg="new websocket client" Apr 13 22:23:18 volumio volumio[946]: info: Connection to go-librespot Websocket established Apr 13 22:23:19 volumio go-librespot[16777]: time="2026-04-13T22:23:19+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]" Apr 13 22:23:21 volumio volumio[946]: info: Getting Spotify volume Apr 13 22:23:21 volumio volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 13 22:23:21 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Apr 13 22:23:21 volumio volumio[946]: info: CorePlayQueue::getTrack 143 Apr 13 22:23:21 volumio go-librespot[16777]: time="2026-04-13T22:23:21+08:00" level=debug msg="completed keyexchange" Apr 13 22:23:21 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:22 volumio go-librespot[16777]: time="2026-04-13T22:23:22+08:00" level=debug msg="completed challenge" Apr 13 22:23:22 volumio go-librespot[16777]: time="2026-04-13T22:23:22+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" Apr 13 22:23:22 volumio volumio[946]: (node:946) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 13 22:23:22 volumio volumio[946]: at connResetException (internal/errors.js:607:14) Apr 13 22:23:22 volumio volumio[946]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 13 22:23:22 volumio volumio[946]: at Socket.emit (events.js:327:22) Apr 13 22:23:22 volumio volumio[946]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 13 22:23:22 volumio volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 13 22:23:22 volumio volumio[946]: (node:946) 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: 734) Apr 13 22:23:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:22 volumio volumio[946]: info: Connection to go-librespot Websocket closed Apr 13 22:23:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:25 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:25 volumio volumio[946]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 730. Apr 13 22:23:25 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:25 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:25 volumio go-librespot[16786]: Librespot-go daemon starting... Apr 13 22:23:25 volumio go-librespot[16786]: time="2026-04-13T22:23:25+08:00" level=info msg="generated new device id: 90de804c9bcf213a46cc8d1650df830f0c4cc8b2" Apr 13 22:23:25 volumio go-librespot[16786]: time="2026-04-13T22:23:25+08:00" level=debug msg="stored credentials not found" Apr 13 22:23:26 volumio go-librespot[16786]: time="2026-04-13T22:23:26+08:00" level=debug msg="obtained new client token: AABeBTUOcO+LAUsTvnqmaOsWq2zJ8pl928S6/W1Cb7+WzcKBJwkOb37wEcn4WI/6YNB1Lx8NFwkPkC4OvqNz1ys4vzS6S7yuq9r0aXwoWGSrnC1nquXFMWRo4XdJvkHd2kl9m9ojxdc1B1N5w/nrdSG0siElBSXPiTvo20OnYqupcd5lXc2a5CRJpXqRbCQFIg9OP93oiJ1OCx/A8N90uuJi+b0Navsdl/IvrmbIUWQAAFuYyHUrdomYEN84b84=" Apr 13 22:23:27 volumio kernel: hwmon hwmon1: Voltage normalised Apr 13 22:23:27 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:28 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:28 volumio go-librespot[16786]: time="2026-04-13T22:23:28+08:00" level=debug msg="new websocket client" Apr 13 22:23:28 volumio volumio[946]: info: Connection to go-librespot Websocket established Apr 13 22:23:28 volumio go-librespot[16786]: time="2026-04-13T22:23:28+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]" Apr 13 22:23:28 volumio go-librespot[16786]: time="2026-04-13T22:23:28+08:00" level=debug msg="completed keyexchange" Apr 13 22:23:29 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:23:29 volumio go-librespot[16786]: time="2026-04-13T22:23:29+08:00" level=debug msg="completed challenge" Apr 13 22:23:29 volumio go-librespot[16786]: time="2026-04-13T22:23:29+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" Apr 13 22:23:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:29 volumio volumio[946]: info: Connection to go-librespot Websocket closed Apr 13 22:23:31 volumio volumio[946]: info: Getting Spotify volume Apr 13 22:23:31 volumio volumio[946]: (node:946) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:31 volumio volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 13 22:23:31 volumio volumio[946]: (node:946) 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: 735) Apr 13 22:23:31 volumio volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 13 22:23:31 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Apr 13 22:23:31 volumio volumio[946]: info: CorePlayQueue::getTrack 143 Apr 13 22:23:32 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:32 volumio volumio[946]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 731. Apr 13 22:23:32 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:32 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:32 volumio go-librespot[16795]: Librespot-go daemon starting... Apr 13 22:23:32 volumio go-librespot[16795]: time="2026-04-13T22:23:32+08:00" level=info msg="generated new device id: 568033f64346e8543ac3b8b81b33dbd847e4ad6a" Apr 13 22:23:32 volumio go-librespot[16795]: time="2026-04-13T22:23:32+08:00" level=debug msg="stored credentials not found" Apr 13 22:23:33 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:34 volumio go-librespot[16795]: time="2026-04-13T22:23:34+08:00" level=debug msg="obtained new client token: AAA61zwyrw2VwhkI2kC4Dk0o4UKGGuBtsgd7q6txeCJ2tLP7o8PzFxVeffV19hIX76BCd2IVmKhaMgzBxOB9PaY2Bs7iRuYZih4LEozdl5mooqwmo1oJNwwFvZcM3p224ZyC4UXd99YR7STeoz8CjZQtWC7K2qShy9mmb91R+5ACHaaiyUvxzioyCRoAd+TfQ7ut+6D7OPUTxFnow0wI3sT8RMz1s+BXNqp3BGJAEiDll4TbbwrKBITvj4Eb6zuVJA==" Apr 13 22:23:35 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:35 volumio go-librespot[16795]: time="2026-04-13T22:23:35+08:00" level=debug msg="new websocket client" Apr 13 22:23:35 volumio volumio[946]: info: Connection to go-librespot Websocket established Apr 13 22:23:35 volumio go-librespot[16795]: time="2026-04-13T22:23: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-gew1.spotify.com:80]" Apr 13 22:23:37 volumio go-librespot[16795]: time="2026-04-13T22:23:37+08:00" level=debug msg="completed keyexchange" Apr 13 22:23:38 volumio go-librespot[16795]: time="2026-04-13T22:23:38+08:00" level=debug msg="completed challenge" Apr 13 22:23:38 volumio go-librespot[16795]: time="2026-04-13T22:23:38+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" Apr 13 22:23:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:38 volumio volumio[946]: info: Connection to go-librespot Websocket closed Apr 13 22:23:38 volumio volumio[946]: info: Getting Spotify volume Apr 13 22:23:38 volumio volumio[946]: (node:946) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:38 volumio volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 13 22:23:38 volumio volumio[946]: (node:946) 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: 736) Apr 13 22:23:38 volumio volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 13 22:23:38 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Apr 13 22:23:38 volumio volumio[946]: info: CorePlayQueue::getTrack 143 Apr 13 22:23:39 volumio kernel: hwmon hwmon1: Voltage normalised Apr 13 22:23:39 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:41 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:41 volumio volumio[946]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 13 22:23:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 732. Apr 13 22:23:41 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:41 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:41 volumio go-librespot[16805]: Librespot-go daemon starting... Apr 13 22:23:41 volumio go-librespot[16805]: time="2026-04-13T22:23:41+08:00" level=info msg="generated new device id: ded99ec0fbc64d869fcf12e19f30ae65811f098c" Apr 13 22:23:41 volumio go-librespot[16805]: time="2026-04-13T22:23:41+08:00" level=debug msg="stored credentials not found" Apr 13 22:23:43 volumio go-librespot[16805]: time="2026-04-13T22:23:43+08:00" level=debug msg="obtained new client token: AADvV/2I2dF7WSPYWvQk4Lml/O2ukNeXqMp8/FqErt0toTRanEJ49AHDR1sBYvxkkzenT8t2+1wCgd0YHZUvPN5UVlxGqYaZYjcFDgpRbSeVHSCa84DDAorBCv33yGSbbMDv1ZSY4y1RKyfv+kDQz8jp008halowe+929t8xM6HhLK5EGngVYhJ77Jsh0DWT5YnsafuXoFNtRQq8ktuyxWF70QQqB0O8R2WYAkI3QB3VvgKo5ZLN/v9To5xhzlcOrQ==" Apr 13 22:23:44 volumio volumio[946]: info: Initializing connection to go-librespot Websocket Apr 13 22:23:44 volumio go-librespot[16805]: time="2026-04-13T22:23:44+08:00" level=debug msg="new websocket client" Apr 13 22:23:44 volumio volumio[946]: info: Connection to go-librespot Websocket established Apr 13 22:23:45 volumio go-librespot[16805]: time="2026-04-13T22:23:45+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]" Apr 13 22:23:45 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:45 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:23:47 volumio go-librespot[16805]: time="2026-04-13T22:23:47+08:00" level=debug msg="completed keyexchange" Apr 13 22:23:47 volumio volumio[946]: info: Getting Spotify volume Apr 13 22:23:47 volumio volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 13 22:23:47 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Apr 13 22:23:47 volumio volumio[946]: info: CorePlayQueue::getTrack 143 Apr 13 22:23:47 volumio go-librespot[16805]: time="2026-04-13T22:23:47+08:00" level=debug msg="completed challenge" Apr 13 22:23:47 volumio go-librespot[16805]: time="2026-04-13T22:23: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" Apr 13 22:23:47 volumio volumio[946]: (node:946) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 13 22:23:47 volumio volumio[946]: at connResetException (internal/errors.js:607:14) Apr 13 22:23:47 volumio volumio[946]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 13 22:23:47 volumio volumio[946]: at Socket.emit (events.js:327:22) Apr 13 22:23:47 volumio volumio[946]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 13 22:23:47 volumio volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 13 22:23:47 volumio volumio[946]: (node:946) 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: 737) Apr 13 22:23:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:47 volumio volumio[946]: info: Connection to go-librespot Websocket closed Apr 13 22:23:49 volumio volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 22:23:49 volumio volumio[946]: Error: connect ETIMEDOUT 185.60.216.11:80 Apr 13 22:23:49 volumio volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 13 22:23:49 volumio volumio[946]: errno: -110, Apr 13 22:23:49 volumio volumio[946]: code: 'ETIMEDOUT', Apr 13 22:23:49 volumio volumio[946]: syscall: 'connect', Apr 13 22:23:49 volumio volumio[946]: address: '185.60.216.11', Apr 13 22:23:49 volumio volumio[946]: port: 80 Apr 13 22:23:49 volumio volumio[946]: } Apr 13 22:23:49 volumio volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 22:23:49 volumio sudo[16824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-13 22:22 Apr 13 22:23:49 volumio sudo[16824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:23:49 volumio sudo[16824]: pam_unix(sudo:session): session closed for user root Apr 13 22:23:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 733. Apr 13 22:23:50 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:50 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:50 volumio go-librespot[16831]: Librespot-go daemon starting... Apr 13 22:23:51 volumio go-librespot[16831]: time="2026-04-13T22:23:51+08:00" level=info msg="generated new device id: 3a2b84b87225534900a68af8f8910d1a30998ae5" Apr 13 22:23:51 volumio go-librespot[16831]: time="2026-04-13T22:23:51+08:00" level=debug msg="stored credentials not found" Apr 13 22:23:51 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:52 volumio volumio-remote-updater[545]: [2026-04-13 22:23:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 13 22:23:52 volumio volumio-remote-updater[545]: [2026-04-13 22:23:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 13 22:23:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:52 volumio systemd[1]: media-9E8C15E78C15BB2B.mount: Succeeded. Apr 13 22:23:52 volumio ntfs-3g[1003]: Unmounting /dev/sda1 () Apr 13 22:23:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 13 22:23:52 volumio kernel: hwmon hwmon1: Voltage normalised Apr 13 22:23:52 volumio systemd[1]: Started dynamicswap service. Apr 13 22:23:52 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 13 22:23:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 13 22:23:52 volumio systemd[1]: Stopped Volumio Backend Module. Apr 13 22:23:52 volumio systemd[1]: Started Volumio Backend Module. Apr 13 22:23:52 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 13 22:23:52 volumio go-librespot[16831]: time="2026-04-13T22:23:52+08:00" level=debug msg="obtained new client token: AAAJdJp6f9y0AW5jKzf1ChQt71za26GDptoVyu/R618PzEEyCWx/E8CYER/tvQg1Jdkr9qhRBlGXEybC4jSl8yrr4orhqi0jBky848dANks3bf3vm80nzVA4JnKoeO3UvWIcT1yFKREDyjypWlchh1ZwxT9oPLysc1xNqICbuj2imD1ogZn1zkbbiGjmzyasW2hrAigPJ7fXq0chiMEAmAO/WPqc+2qZse8SthEG+9fzjQJGsIb96cBQXSqfBuMUPA==" Apr 13 22:23:54 volumio volumio[16846]: info: ------------------------------------------- Apr 13 22:23:54 volumio volumio[16846]: info: ----- Volumio3 ---- Apr 13 22:23:54 volumio volumio[16846]: info: ------------------------------------------- Apr 13 22:23:54 volumio volumio[16846]: info: ----- System startup ---- Apr 13 22:23:54 volumio volumio[16846]: info: ------------------------------------------- Apr 13 22:23:54 volumio go-librespot[16831]: time="2026-04-13T22:23:54+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]" Apr 13 22:23:54 volumio volumio[16846]: info: MYVOLUMIO Environment detected Apr 13 22:23:54 volumio volumio[16846]: info: Plugin folders cleanup Apr 13 22:23:54 volumio volumio[16846]: info: Scanning into folder /volumio/app/plugins/ Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category audio_interface Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category miscellanea Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category music_service Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category plugins.json Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category system_controller Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category user_interface Apr 13 22:23:54 volumio volumio[16846]: info: Scanning into folder /data/plugins/ Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category music_service Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category system_controller Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category system_hardware Apr 13 22:23:54 volumio volumio[16846]: info: Scanning category user_interface Apr 13 22:23:54 volumio volumio[16846]: info: Plugin folders cleanup completed Apr 13 22:23:54 volumio volumio[16846]: info: ------------------------------------------- Apr 13 22:23:54 volumio volumio[16846]: info: ----- Core plugins startup ---- Apr 13 22:23:54 volumio volumio[16846]: info: ------------------------------------------- Apr 13 22:23:54 volumio volumio[16846]: info: Loading plugins from folder /volumio/app/plugins/ Apr 13 22:23:54 volumio volumio[16846]: info: Adding plugin upnp to MyMusic Plugins Apr 13 22:23:54 volumio volumio[16846]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 13 22:23:54 volumio volumio[16846]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 13 22:23:54 volumio volumio[16846]: info: Loading plugins from folder /data/plugins/ Apr 13 22:23:54 volumio volumio[16846]: info: Loading plugin "system"... Apr 13 22:23:54 volumio volumio[16846]: info: Loading plugin "appearance"... Apr 13 22:23:55 volumio volumio[16846]: info: Loading plugin "network"... Apr 13 22:23:55 volumio volumio[16846]: info: Refreshing Cached IP Addresses Apr 13 22:23:55 volumio sudo[16871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 22:23:55 volumio sudo[16871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:23:55 volumio sudo[16871]: pam_unix(sudo:session): session closed for user root Apr 13 22:23:55 volumio volumio[16846]: info: Loading plugin "services"... Apr 13 22:23:55 volumio volumio[16846]: info: Loading plugin "alsa_controller"... Apr 13 22:23:55 volumio sudo[16873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 22:23:55 volumio sudo[16873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:23:55 volumio sudo[16873]: pam_unix(sudo:session): session closed for user root Apr 13 22:23:55 volumio sudo[16876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 13 22:23:55 volumio sudo[16876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:23:55 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 22:23:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:23:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 13 22:23:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:23:55 volumio volumio[16846]: info: Loading plugin "wizard"... Apr 13 22:23:55 volumio volumio[16846]: info: Loading plugin "networkfs"... Apr 13 22:23:55 volumio volumio[16846]: info: Starting Udev Watcher for removable devices Apr 13 22:23:55 volumio volumio[16846]: info: Ignoring mount for partition: boot Apr 13 22:23:55 volumio volumio[16846]: info: Ignoring mount for partition: volumio Apr 13 22:23:55 volumio volumio[16846]: info: Ignoring mount for partition: volumio_data Apr 13 22:23:55 volumio volumio[16846]: info: Mounting Device 9E8C15E78C15BB2B Apr 13 22:23:55 volumio sudo[16887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/9E8C15E78C15BB2B -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 13 22:23:55 volumio sudo[16887]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 28 00 00 f8 00 Apr 13 22:23:56 volumio kernel: critical medium error, dev sda, sector 296 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 02 28 00 01 f8 00 Apr 13 22:23:56 volumio kernel: critical medium error, dev sda, sector 552 op 0x0:(READ) flags 0x80700 phys_seg 63 prio class 2 Apr 13 22:23:56 volumio go-librespot[16831]: time="2026-04-13T22:23:56+08:00" level=debug msg="completed keyexchange" Apr 13 22:23:56 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Apr 13 22:23:56 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 38 00 01 00 00 Apr 13 22:23:56 volumio kernel: critical medium error, dev sda, sector 312 op 0x0:(READ) flags 0x80700 phys_seg 26 prio class 2 Apr 13 22:23:56 volumio ntfs-3g[16890]: Version 2017.3.23AR.3 integrated FUSE 28 Apr 13 22:23:56 volumio ntfs-3g[16890]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1) Apr 13 22:23:56 volumio ntfs-3g[16890]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 13 22:23:56 volumio ntfs-3g[16890]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 13 22:23:56 volumio ntfs-3g[16890]: Global ownership and permissions enforced, configuration type 7 Apr 13 22:23:56 volumio sudo[16887]: pam_unix(sudo:session): session closed for user root Apr 13 22:23:56 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 22:23:56 volumio volumio[16846]: info: Loading plugin "volumio_command_line_client"... Apr 13 22:23:56 volumio volumio[16846]: info: Plugin upnp is not enabled Apr 13 22:23:56 volumio volumio[16846]: info: Loading plugin "my_music"... Apr 13 22:23:56 volumio volumio[16846]: info: Loading plugin "mpd"... Apr 13 22:23:56 volumio sudo[16876]: pam_unix(sudo:session): session closed for user root Apr 13 22:23:56 volumio go-librespot[16831]: time="2026-04-13T22:23:56+08:00" level=debug msg="completed challenge" Apr 13 22:23:56 volumio go-librespot[16831]: time="2026-04-13T22:23: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" Apr 13 22:23:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:23:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:23:57 volumio volumio-remote-updater[545]: [2026-04-13 22:23:57] [connect] Successful connection Apr 13 22:23:57 volumio volumio[16846]: info: Loading plugin "upnp_browser"... Apr 13 22:23:57 volumio volumio[16846]: info: Loading plugin "alarm-clock"... Apr 13 22:23:57 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:23:57 volumio volumio[16846]: info: Plugin airplay_emulation is not enabled Apr 13 22:23:57 volumio volumio[16846]: info: Loading plugin "last_100"... Apr 13 22:23:57 volumio volumio[16846]: info: Loading plugin "webradio"... Apr 13 22:23:58 volumio volumio[16846]: info: Loading plugin "i2s_dacs"... Apr 13 22:23:58 volumio volumio[16846]: info: Loading plugin "volumiodiscovery"... Apr 13 22:23:58 volumio volumio[16846]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 22:23:58 volumio node[16846]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 22:23:58 volumio volumio[16846]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 22:23:58 volumio node[16846]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 22:23:58 volumio volumio[16846]: *** WARNING *** For more information see Apr 13 22:23:58 volumio node[16846]: *** WARNING *** For more information see Apr 13 22:23:58 volumio volumio[16846]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 22:23:58 volumio node[16846]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 22:23:58 volumio volumio[16846]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 22:23:58 volumio node[16846]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 22:23:58 volumio volumio[16846]: *** WARNING *** For more information see Apr 13 22:23:58 volumio node[16846]: *** WARNING *** For more information see Apr 13 22:23:58 volumio volumio[16846]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 13 22:23:58 volumio volumio[16846]: info: Discovery: Started advertising with name: Volumio Apr 13 22:23:58 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 22:23:58 volumio volumio[16846]: info: Loading plugin "spop"... Apr 13 22:23:59 volumio volumio[16846]: info: Loading plugin "autostart"... Apr 13 22:23:59 volumio volumio[16846]: info: Applying required configuration parameters for plugin autostart Apr 13 22:23:59 volumio volumio[16846]: info: AutoStart - onVolumioStart - read config.json Apr 13 22:23:59 volumio volumio[16846]: info: Loading plugin "now_playing"... Apr 13 22:23:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:23:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 734. Apr 13 22:23:59 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:23:59 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:23:59 volumio go-librespot[16893]: Librespot-go daemon starting... Apr 13 22:23:59 volumio go-librespot[16893]: time="2026-04-13T22:23:59+08:00" level=info msg="generated new device id: 95426a9da4d3b6a53b050f488cab640f06d1b2ae" Apr 13 22:23:59 volumio go-librespot[16893]: time="2026-04-13T22:23:59+08:00" level=debug msg="stored credentials not found" Apr 13 22:24:00 volumio volumio[16846]: info: Loading plugin "peppy_screensaver"... Apr 13 22:24:01 volumio volumio[16846]: info: Applying required configuration parameters for plugin peppy_screensaver Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "outputs"... Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "albumart"... Apr 13 22:24:01 volumio volumio[16846]: info: Plugin example_plugin is not enabled Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "inputs"... Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "updater_comm"... Apr 13 22:24:01 volumio volumio[16846]: info: Plugin mpdemulation is not enabled Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "rest_api"... Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "websocket"... Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "80s80s"... Apr 13 22:24:01 volumio volumio[16846]: Forking 3 albumart workers Apr 13 22:24:01 volumio volumio[16846]: info: Applying required configuration parameters for plugin 80s80s Apr 13 22:24:01 volumio volumio[16846]: info: [1776090241755] [80s80s] API delay: 30 Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "RoonBridge"... Apr 13 22:24:01 volumio volumio[16846]: info: Applying required configuration parameters for plugin RoonBridge Apr 13 22:24:01 volumio volumio[16846]: info: Loading plugin "lms"... Apr 13 22:24:02 volumio go-librespot[16893]: time="2026-04-13T22:24:02+08:00" level=debug msg="obtained new client token: AACYr87Ftl3YbYUY6gYtewG40+eqAEVWZux07QE0ChLr40UTlqg2oaWRmtyoov3M3fcJ39bGMC6f9y+g/4VuP9FfNI/DhTvv1emzeyv4iP/mhRP2+QmquIq4ZTHgrfdgvNovVq/B4YvTtBacSQsbYNbbwI/C7rxu9hUHr2QIiS88tCWPt07JtzSyWmhu+coJ+/ZB+TCABHmSgLQnTNARKwJBo9ixNFImyYSNhf2VSwmd10iqVjbNKM0rDNs7Cs1Vlw==" Apr 13 22:24:02 volumio volumio[16846]: info: Loading plugin "podcast"... Apr 13 22:24:02 volumio volumio[16846]: info: ControllerPodcast::constructor Apr 13 22:24:02 volumio volumio[16846]: info: Loading plugin "ir_controller"... Apr 13 22:24:02 volumio kernel: hwmon hwmon1: Voltage normalised Apr 13 22:24:02 volumio volumio[16846]: info: Applying required configuration parameters for plugin ir_controller Apr 13 22:24:02 volumio volumio[16846]: Starting albumart workers Apr 13 22:24:02 volumio volumio[16846]: info: Loading plugin "Systeminfo"... Apr 13 22:24:02 volumio volumio[16846]: Starting albumart workers Apr 13 22:24:02 volumio volumio[16846]: Starting albumart workers Apr 13 22:24:03 volumio volumio[16846]: info: Loading plugin "touch_display"... Apr 13 22:24:03 volumio go-librespot[16893]: time="2026-04-13T22:24:03+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]" Apr 13 22:24:03 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:24:03 volumio volumio[16846]: info: Applying required configuration parameters for plugin touch_display Apr 13 22:24:04 volumio volumio[16846]: info: Loading i18n strings for locale zh Apr 13 22:24:04 volumio volumio[16846]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 22:24:04 volumio volumio[16846]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Apr 13 22:24:04 volumio volumio[16846]: Updating browse sources language Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::initPlayerControls Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 22:24:04 volumio volumio[16846]: Express server listening on port 3000 Apr 13 22:24:04 volumio volumio[16846]: [Metrics] WebUI: 11s 73.19ms Apr 13 22:24:04 volumio volumio[16846]: info: CoreStateMachine::resetVolumioState Apr 13 22:24:04 volumio volumio[16846]: info: CoreStateMachine::getcurrentVolume Apr 13 22:24:04 volumio volumio[16846]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 22:24:04 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:24:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 13 22:24:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:04 volumio volumio[16846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 Apr 13 22:24:05 volumio volumio[16846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:05 volumio volumio[16846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 22:24:05 volumio volumio[16846]: info: VolumeController:: Volume=76 Mute =true Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::pushState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioPushState Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::updateTrackBlock Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrackBlock Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::setRepeat true single undefined Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::pushState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioPushState Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::setRandom false Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::pushState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioPushState Apr 13 22:24:05 volumio volumio[16846]: info: Setting Device type: Raspberry PI Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:05 volumio volumio[16846]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Apr 13 22:24:05 volumio volumio[16846]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Apr 13 22:24:05 volumio volumio[16846]: info: Listing playlists Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetQueue Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::getQueue Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getQueue Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 13 22:24:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:05 volumio go-librespot[16893]: time="2026-04-13T22:24:05+08:00" level=debug msg="completed keyexchange" Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:05 volumio volumio[16846]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Apr 13 22:24:05 volumio volumio[16846]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Apr 13 22:24:05 volumio volumio[16846]: info: Listing playlists Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetQueue Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::getQueue Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getQueue Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:05 volumio volumio[16846]: info: VolumeController:: Volume=76 Mute =true Apr 13 22:24:05 volumio volumio[16846]: info: CoreStateMachine::pushState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioPushState Apr 13 22:24:05 volumio volumio[16846]: info: Discovery: adding eaa2afdc-3fa0-4a8a-925d-a9b0c99b88c0 Apr 13 22:24:05 volumio volumio[16846]: info: Discovery: Found device Volumio Apr 13 22:24:05 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:05 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:06 volumio go-librespot[16893]: time="2026-04-13T22:24:06+08:00" level=debug msg="completed challenge" Apr 13 22:24:06 volumio go-librespot[16893]: time="2026-04-13T22:24:06+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" Apr 13 22:24:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:24:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:24:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 13 22:24:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:24:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 735. Apr 13 22:24:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:24:09 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:24:09 volumio go-librespot[17010]: Librespot-go daemon starting... Apr 13 22:24:09 volumio go-librespot[17010]: time="2026-04-13T22:24:09+08:00" level=info msg="generated new device id: fe2079f187c0b04abb1f5e06a1237f8662a19cee" Apr 13 22:24:09 volumio go-librespot[17010]: time="2026-04-13T22:24:09+08:00" level=debug msg="stored credentials not found" Apr 13 22:24:09 volumio volumio-remote-updater[545]: [2026-04-13 22:24:09] [connect] Successful connection Apr 13 22:24:09 volumio volumio[16846]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Apr 13 22:24:09 volumio volumio-remote-updater[545]: [2026-04-13 22:24:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1776090249 101 Apr 13 22:24:09 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:24:10 volumio go-librespot[17010]: time="2026-04-13T22:24:10+08:00" level=debug msg="obtained new client token: AAAYcmeFo2NQQXvLdUBJvDlFLMNxY/Gt10ucItrH9uEU0M4aGmPX5bn3m+0pKiWZ6UBoTj+080txvKjcbgcYHeTtJ2Zpz/hlQYyGy8xE+NOYCudDlrsDj3vhICMNRs+Apo9sDUAzoa0uqrlFMRZTDASd7Nf9ZVwYo0fwYwPRGVnriBIDHzxAJFY6PBWVK7Jw5umDBQWpmhUo3RrQda18nzWGKc1/tdavfKd3e7DTyyliEj+6v//0HyzbzJsGkkfy5Q==" Apr 13 22:24:10 volumio kernel: hwmon hwmon1: Voltage normalised Apr 13 22:24:13 volumio go-librespot[17010]: time="2026-04-13T22:24:13+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]" Apr 13 22:24:15 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:24:15 volumio go-librespot[17010]: time="2026-04-13T22:24:15+08:00" level=debug msg="completed keyexchange" Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 22:24:16 volumio volumio[16846]: info: Discovery: Getting this device information Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:16 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 22:24:16 volumio go-librespot[17010]: time="2026-04-13T22:24:16+08:00" level=debug msg="completed challenge" Apr 13 22:24:16 volumio volumio[16846]: verbose: New Socket.io Connection to 192.168.101.4 from 192.168.101.9 UA: okhttp/4.9.2 Total Clients: 5 Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:16 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:16 volumio go-librespot[17010]: time="2026-04-13T22:24:16+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" Apr 13 22:24:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 13 22:24:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 22:24:16 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 22:24:19 volumio sudo[17020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 22:24:19 volumio sudo[17020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:24:19 volumio sudo[17020]: pam_unix(sudo:session): session closed for user root Apr 13 22:24:19 volumio sudo[17023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 22:24:19 volumio sudo[17023]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 22:24:19 volumio sudo[17023]: pam_unix(sudo:session): session closed for user root Apr 13 22:24:19 volumio volumio[16846]: verbose: New Socket.io Connection to 192.168.101.4 from 192.168.101.9 UA: okhttp/4.9.2 Total Clients: 6 Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:19 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 22:24:19 volumio volumio[16846]: info: Received Get System Info Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 22:24:19 volumio volumio[16846]: info: Discovery: Getting this device information Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:19 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:19 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:19 volumio volumio[16846]: info: Listing playlists Apr 13 22:24:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 13 22:24:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 736. Apr 13 22:24:19 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 13 22:24:19 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 22:24:19 volumio systemd[1]: Started go-librespot Daemon. Apr 13 22:24:19 volumio go-librespot[17025]: Librespot-go daemon starting... Apr 13 22:24:19 volumio go-librespot[17025]: time="2026-04-13T22:24:19+08:00" level=info msg="generated new device id: 7644de306f0c01fcc88475b459f1c320ba2e5fdd" Apr 13 22:24:19 volumio go-librespot[17025]: time="2026-04-13T22:24:19+08:00" level=debug msg="stored credentials not found" Apr 13 22:24:20 volumio volumio[16846]: info: CoreCommandRouter::volumioGetQueue Apr 13 22:24:20 volumio volumio[16846]: info: CoreStateMachine::getQueue Apr 13 22:24:20 volumio volumio[16846]: info: CorePlayQueue::getQueue Apr 13 22:24:21 volumio go-librespot[17025]: time="2026-04-13T22:24:21+08:00" level=debug msg="obtained new client token: AACXIUYjVJoDS75QF2Cty8uMEdN0L7237c770tCO2rlELcfWqaPKPbOqanrvMOt2LOy5uvjTXbNFUIKZ1vx9TVm0lQXYUgkboOJ1kj7nU9hgIsB3cB3shMDqZ6H3GxtskbZXzLlyZc7KB1kKZxcGWYLmFzMfil8xDBRMry7qGBzoDemRyyFxRwnz+A7i685/00whbqzbpMMxhx+GvTd0orpFwvXDAtr3X7LbZ4SZpWM42zlOMkVKrlVTJm3VQcxfYg==" Apr 13 22:24:21 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 13 22:24:21 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 22:24:21 volumio volumio[16846]: info: Received Get System Info Apr 13 22:24:21 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 22:24:21 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 22:24:21 volumio volumio[16846]: info: Discovery: Getting this device information Apr 13 22:24:21 volumio volumio[16846]: info: CoreCommandRouter::volumioGetState Apr 13 22:24:21 volumio volumio[16846]: info: CorePlayQueue::getTrack 0 Apr 13 22:24:21 volumio volumio[16846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 22:24:21 volumio wpa_supplicant[789]: wlan0: Failed to initiate sched scan Apr 13 22:24:23 volumio go-librespot[17025]: time="2026-04-13T22:24:23+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]" Apr 13 22:24:23 volumio volumio[16846]: info: Preload queue cleared Apr 13 22:24:23 volumio volumio[16846]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 13 22:24:23 volumio volumio[16846]: info: CoreStateMachine::ClearQueue Apr 13 22:24:23 volumio volumio[16846]: info: CoreStateMachine::stop Apr 13 22:24:23 volumio volumio[16846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 22:24:23 volumio volumio[16846]: info: CorePlayQueue::clearPlayQueue Apr 13 22:24:23 volumio volumio[16846]: info: CorePlayQueue::saveQueue Apr 13 22:24:23 volumio volumio[16846]: info: CoreCommandRouter::volumioPushQueue Apr 13 22:24:23 volumio volumio[16846]: info: CoreStateMachine::addQueueItems Apr 13 22:24:23 volumio volumio[16846]: info: CorePlayQueue::addQueueItems Apr 13 22:24:23 volumio volumio[16846]: info: Preload queue cleared Apr 13 22:24:23 volumio volumio[16846]: info: Adding Item to queue: music-library/USB/9E8C15E78C15BB2B/音乐/Achim Reichel - Aloha Heja He.flac Apr 13 22:24:23 volumio volumio[16846]: info: Exploding uri music-library/USB/9E8C15E78C15BB2B/音乐/Achim Reichel - Aloha Heja He.flac in service mpd Apr 13 22:24:23 volumio volumio[16846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 22:24:23 volumio volumio[16846]: TypeError: Cannot read property 'then' of undefined Apr 13 22:24:23 volumio volumio[16846]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 13 22:24:23 volumio volumio[16846]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 13 22:24:23 volumio volumio[16846]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 13 22:24:23 volumio volumio[16846]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 13 22:24:23 volumio volumio[16846]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 13 22:24:23 volumio volumio[16846]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 13 22:24:23 volumio volumio[16846]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 13 22:24:23 volumio volumio[16846]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:501:23) Apr 13 22:24:23 volumio volumio[16846]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 13 22:24:23 volumio volumio[16846]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 13 22:24:23 volumio volumio[16846]: at Socket.emit (events.js:315:20) Apr 13 22:24:23 volumio volumio[16846]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 13 22:24:23 volumio volumio[16846]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 13 22:24:23 volumio volumio[16846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 22:24:24 volumio sudo[17045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-13 22:23 Apr 13 22:24:24 volumio sudo[17045]: 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"