-- Logs begin at Sun 2025-12-28 16:10:25 CST, end at Sun 2025-12-28 21:40:35 CST. --
Dec 28 21:39:00 volumio go-librespot[6867]: time="2025-12-28T21:39:00+08:00" level=debug msg="obtained new client token: AAAbFxrKBuWQkSc96NiB/wUzSkwZ9T/DCZKRmiKZy9UbOvWRcdmiaWL4ms9FnD6coLZkj/AYXJPfoyR2IBaBrO/CH06mygKEUTSSFHrKCf7hVTgp3N1ZlIeUG33M+YTBjbjRcweAZVPYYOqxxT9jHO4H1aW0ILt6b51jv+fbMA9+RjxqeAaZCaOfXEEmUlGHmPePkEDxNjplf+JdB6Vu39K+AgWR9g9UvUCu9fxBxx8t8PHFqkHhuWL0rPiryQE="
Dec 28 21:39:01 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:02 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:39:02 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Dec 28 21:39:02 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:02 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:02 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:39:02 volumio go-librespot[6867]: time="2025-12-28T21:39:02+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 28 21:39:04 volumio go-librespot[6867]: time="2025-12-28T21:39:04+08:00" level=debug msg="completed keyexchange"
Dec 28 21:39:05 volumio go-librespot[6867]: time="2025-12-28T21:39:05+08:00" level=debug msg="completed challenge"
Dec 28 21:39:05 volumio go-librespot[6867]: time="2025-12-28T21:39:05+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:39:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:39:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:39:05 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:39:05 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:39:05 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:39:05 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:39:05 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:39:05 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:39:05 volumio volumio[938]: (node:938) 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: 303)
Dec 28 21:39:05 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:39:06 volumio kernel: hwmon hwmon1: Undervoltage detected!
Dec 28 21:39:07 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:08 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:08 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:39:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 300.
Dec 28 21:39:08 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:39:08 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:39:08 volumio go-librespot[6905]: Librespot-go daemon starting...
Dec 28 21:39:08 volumio go-librespot[6905]: time="2025-12-28T21:39:08+08:00" level=info msg="generated new device id: f59834b6df4c04b506a66e08eb3d44e5c529eed5"
Dec 28 21:39:08 volumio go-librespot[6905]: time="2025-12-28T21:39:08+08:00" level=debug msg="stored credentials not found"
Dec 28 21:39:10 volumio go-librespot[6905]: time="2025-12-28T21:39:10+08:00" level=debug msg="obtained new client token: AABJgazXsYoMn9vDy0JJjs8p32DxVqDmDgbwFClH4Qo4TjAQVJgozo3foiIkltR03x1ORBhPvLbrwTXGRbVK/mIrjKrnenD/UyDB7icdsVAy03j90BaiVY/nrSwKCY2Ps3NQs9POakBlMcqL1ksqbbPNGf1FTBItfdvdwNtUp2A6aMkEk6vyQVkFbwdHyJbklMFi2ldb2Thw/xmykQ0sjUZmwfXaHJbYPVERN9MekgwH2bPytEFe3GKOPX6GSBzEXA=="
Dec 28 21:39:11 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:11 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:39:11 volumio go-librespot[6905]: time="2025-12-28T21:39:11+08:00" level=debug msg="new websocket client"
Dec 28 21:39:12 volumio go-librespot[6905]: time="2025-12-28T21:39: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]"
Dec 28 21:39:13 volumio kernel: hwmon hwmon1: Voltage normalised
Dec 28 21:39:13 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:14 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:39:14 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Dec 28 21:39:14 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:14 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:14 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:39:14 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 9
Dec 28 21:39:14 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:14 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:14 volumio go-librespot[6905]: time="2025-12-28T21:39:14+08:00" level=debug msg="completed keyexchange"
Dec 28 21:39:15 volumio go-librespot[6905]: time="2025-12-28T21:39:15+08:00" level=debug msg="completed challenge"
Dec 28 21:39:15 volumio go-librespot[6905]: time="2025-12-28T21:39:15+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:39:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:39:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:39:15 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:39:15 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:39:15 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:39:15 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:39:15 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:39:15 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:39:15 volumio volumio[938]: (node:938) 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: 304)
Dec 28 21:39:15 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:39:18 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:18 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:39:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 301.
Dec 28 21:39:18 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:39:18 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:39:18 volumio go-librespot[6944]: Librespot-go daemon starting...
Dec 28 21:39:18 volumio go-librespot[6944]: time="2025-12-28T21:39:18+08:00" level=info msg="generated new device id: 61aed953328e6af7ea5643e752af085b589c0e16"
Dec 28 21:39:18 volumio go-librespot[6944]: time="2025-12-28T21:39:18+08:00" level=debug msg="stored credentials not found"
Dec 28 21:39:19 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:19 volumio kernel: hwmon hwmon1: Undervoltage detected!
Dec 28 21:39:21 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:21 volumio go-librespot[6944]: time="2025-12-28T21:39:21+08:00" level=debug msg="new websocket client"
Dec 28 21:39:21 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:39:23 volumio go-librespot[6944]: time="2025-12-28T21:39:23+08:00" level=debug msg="obtained new client token: AACloSfq1vd7FAl4lRcEE52+tIwpqlAmPzGwdMWPjkp25IKObLOxgdg3xn+HK4D73ZRvQB54pJYQUH9W6HHAJCJBDh9ilQoYHCKniCQ44Sd4dWNSdoGGCaQyHK5rSBFp1Ce1vxyp8aWKLz7i28EEvMimMFu6+UTB9LqRr9UrHGatw3yxKmjavDW8BOOUpFmSQBeBBWLLp5AyvWa9cVe23OxHEFWWt4JPupg7i8jqK94QUSpHF+BRmmx80LALp/2htQ=="
Dec 28 21:39:24 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:39:24 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Dec 28 21:39:24 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:24 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:24 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:39:25 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:25 volumio kernel: hwmon hwmon1: Voltage normalised
Dec 28 21:39:29 volumio go-librespot[6944]: time="2025-12-28T21:39:29+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 28 21:39:29 volumio go-librespot[6944]: time="2025-12-28T21:39:29+08:00" level=debug msg="completed keyexchange"
Dec 28 21:39:30 volumio go-librespot[6944]: time="2025-12-28T21:39:30+08:00" level=debug msg="completed challenge"
Dec 28 21:39:30 volumio go-librespot[6944]: time="2025-12-28T21:39:30+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:39:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:39:30 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:39:30 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:39:30 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:39:30 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:39:30 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:39:30 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:39:30 volumio volumio[938]: (node:938) 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: 305)
Dec 28 21:39:30 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:39:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:39:31 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:31 volumio kernel: hwmon hwmon1: Undervoltage detected!
Dec 28 21:39:33 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:33 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:39:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 302.
Dec 28 21:39:33 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:39:33 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:39:33 volumio go-librespot[6959]: Librespot-go daemon starting...
Dec 28 21:39:33 volumio go-librespot[6959]: time="2025-12-28T21:39:33+08:00" level=info msg="generated new device id: 67657c1f24986c840b8623363c0590616a687316"
Dec 28 21:39:33 volumio go-librespot[6959]: time="2025-12-28T21:39:33+08:00" level=debug msg="stored credentials not found"
Dec 28 21:39:34 volumio go-librespot[6959]: time="2025-12-28T21:39:34+08:00" level=debug msg="obtained new client token: AACNozWxESBDit5mH/lRw1VJxNqC+PP16bISTDg1Ek69iglqWveFdQ2dDYfrqECeX7HY4Setw4/6QLmAVoBgbSVDaIH0tPRKijy4L/ryrZtUsdNGJO3oXG4TgD4+kC2Mv1Zk/v2LU6jsAxEUwQ7IPNWy7JJFksX3tQJVfKVQuuuXPaZzsdMqeQC1n9MVaWgKIC/fxm/CKcQSpZwyiM2Yg2dQg6GLalgfziA4TgqqY/gshhdMMyxgCyG/MuBPPhRPfg=="
Dec 28 21:39:36 volumio kernel: hwmon hwmon1: Voltage normalised
Dec 28 21:39:36 volumio go-librespot[6959]: time="2025-12-28T21:39:36+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 28 21:39:36 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:36 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:39:36 volumio go-librespot[6959]: time="2025-12-28T21:39:36+08:00" level=debug msg="new websocket client"
Dec 28 21:39:36 volumio go-librespot[6959]: time="2025-12-28T21:39:36+08:00" level=debug msg="completed keyexchange"
Dec 28 21:39:37 volumio go-librespot[6959]: time="2025-12-28T21:39:37+08:00" level=debug msg="completed challenge"
Dec 28 21:39:37 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:37 volumio go-librespot[6959]: time="2025-12-28T21:39:37+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:39:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:39:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:39:37 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:39:39 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:39:39 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:39 volumio volumio[938]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Dec 28 21:39:39 volumio volumio[938]: (node:938) 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: 306)
Dec 28 21:39:39 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Dec 28 21:39:39 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:39 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:39 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:39:40 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:40 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:39:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 303.
Dec 28 21:39:40 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:39:40 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:39:40 volumio go-librespot[7009]: Librespot-go daemon starting...
Dec 28 21:39:40 volumio go-librespot[7009]: time="2025-12-28T21:39:40+08:00" level=info msg="generated new device id: 785588d406d36bc264444967e251bad3d62ea1e0"
Dec 28 21:39:40 volumio go-librespot[7009]: time="2025-12-28T21:39:40+08:00" level=debug msg="stored credentials not found"
Dec 28 21:39:41 volumio go-librespot[7009]: time="2025-12-28T21:39:41+08:00" level=debug msg="obtained new client token: AAABOn/W0www+S6LpePm3LAvAmPKzM2myAFc2qAHl5T9wFb15pged0cjLNhlbvWsTJ0wajOIlNihUdAbovNMLQ1lB4hWb+ii5In+GIL/Z4/yfh8Zy4E1ljtqL0ceqTBmFo9Fkj955C6bgjNjVIxsSOIMkZfI9bGdkbOZwnMmmLtt2IRqdQXjsfYOLAxIchqgT8072aHeerpOFVY/iwmJ4ux/WbxmhL+0iS1EIKKpPYSvyL93mLensWCCLDM1Tk2COw=="
Dec 28 21:39:43 volumio go-librespot[7009]: time="2025-12-28T21:39:43+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 28 21:39:43 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:43 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:43 volumio go-librespot[7009]: time="2025-12-28T21:39:43+08:00" level=debug msg="new websocket client"
Dec 28 21:39:43 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:39:45 volumio go-librespot[7009]: time="2025-12-28T21:39:45+08:00" level=debug msg="completed keyexchange"
Dec 28 21:39:46 volumio go-librespot[7009]: time="2025-12-28T21:39:46+08:00" level=debug msg="completed challenge"
Dec 28 21:39:46 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:39:46 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Dec 28 21:39:46 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:46 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:46 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:39:46 volumio go-librespot[7009]: time="2025-12-28T21:39:46+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:39:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:39:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:39:46 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:39:46 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:39:46 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:39:46 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:39:46 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:39:46 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:39:46 volumio volumio[938]: (node:938) 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: 307)
Dec 28 21:39:46 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:39:49 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:49 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:49 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:39:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 304.
Dec 28 21:39:49 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:39:49 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:39:49 volumio go-librespot[7018]: Librespot-go daemon starting...
Dec 28 21:39:49 volumio go-librespot[7018]: time="2025-12-28T21:39:49+08:00" level=info msg="generated new device id: 87f57ec66feff005f140d082984853e272f55e94"
Dec 28 21:39:49 volumio go-librespot[7018]: time="2025-12-28T21:39:49+08:00" level=debug msg="stored credentials not found"
Dec 28 21:39:50 volumio go-librespot[7018]: time="2025-12-28T21:39:50+08:00" level=debug msg="obtained new client token: AADE3BBcvWbuIPm8l2K2Fext1x8BwgGTjcsMcWr+nWD6Qm48zecZFH1oR8sIU2LptbSR4PHrKeK//V7mmdSCPV6lxuzAq2V8sBwzShGHzENWrNT8b+XPOrb8ZPzuRkWJR597ceuDRRds6fNmaH8kQgbMixJ4L/UehkKrlkY8eneQM/h/T2c2ljmkLLaGxNfM0acXVdIWzeOTd5PjQug0Rv8XOZKMpvgq4m2WgDrDJWAu2f7w6hUYBJL9BUWhEwU/vg=="
Dec 28 21:39:52 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:52 volumio go-librespot[7018]: time="2025-12-28T21:39:52+08:00" level=debug msg="new websocket client"
Dec 28 21:39:52 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:39:53 volumio go-librespot[7018]: time="2025-12-28T21:39:53+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 28 21:39:54 volumio kernel: hwmon hwmon1: Undervoltage detected!
Dec 28 21:39:55 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:39:55 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:39:55 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Dec 28 21:39:55 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:39:55 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:39:55 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:39:56 volumio go-librespot[7018]: time="2025-12-28T21:39:56+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: read tcp 192.168.101.4:40940->104.199.241.202:4070: read: connection reset by peer"
Dec 28 21:39:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:39:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:39:56 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:39:56 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:39:56 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:39:56 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:39:56 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:39:56 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:39:56 volumio volumio[938]: (node:938) 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: 308)
Dec 28 21:39:56 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:39:59 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:39:59 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:39:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:39:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 305.
Dec 28 21:39:59 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:39:59 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:39:59 volumio go-librespot[7031]: Librespot-go daemon starting...
Dec 28 21:39:59 volumio go-librespot[7031]: time="2025-12-28T21:39:59+08:00" level=info msg="generated new device id: 39e570e2dc630b9d181c4ec9f94706f20f7a964f"
Dec 28 21:39:59 volumio go-librespot[7031]: time="2025-12-28T21:39:59+08:00" level=debug msg="stored credentials not found"
Dec 28 21:40:00 volumio kernel: hwmon hwmon1: Voltage normalised
Dec 28 21:40:01 volumio go-librespot[7031]: time="2025-12-28T21:40:01+08:00" level=debug msg="obtained new client token: AAC+hJglB94mI1pZwBIWdWw3lXYKBiCR95/HdkqxD1R+XS068ZvpFqXyjg4sOCaldJKBq5KxRFmA/GeGjNBqVcID0EzUnh3kyhBRcZ4leWlyGrh4FSJPY7FIpCPIvB+3+YuU9HCE11zaZQCng25nVpd5B7zi/aMQ5nLe94rznMvO8TIgJzCQmiCXJJTXPod3OGaJ+CLIY2JfIdIdPaZuh0Yr9nnpM4tc2OZJixvTZDntM/qj9Y3SWFLbVWu686O98g=="
Dec 28 21:40:01 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:40:02 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:02 volumio go-librespot[7031]: time="2025-12-28T21:40:02+08:00" level=debug msg="new websocket client"
Dec 28 21:40:02 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:40:04 volumio go-librespot[7031]: time="2025-12-28T21:40:04+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 28 21:40:05 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:40:05 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Dec 28 21:40:05 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:40:05 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:40:05 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:07 volumio go-librespot[7031]: time="2025-12-28T21:40:07+08:00" level=debug msg="completed keyexchange"
Dec 28 21:40:07 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:40:07 volumio go-librespot[7031]: time="2025-12-28T21:40:07+08:00" level=debug msg="completed challenge"
Dec 28 21:40:07 volumio go-librespot[7031]: time="2025-12-28T21:40:07+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:40:07 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:40:07 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:40:07 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:40:07 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:40:07 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:40:07 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:40:07 volumio volumio[938]: (node:938) 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: 309)
Dec 28 21:40:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:40:07 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:40:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:40:10 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:10 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:40:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:40:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 306.
Dec 28 21:40:10 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:40:10 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:40:10 volumio go-librespot[7040]: Librespot-go daemon starting...
Dec 28 21:40:10 volumio go-librespot[7040]: time="2025-12-28T21:40:10+08:00" level=info msg="generated new device id: 2f83ae6789bd7fc16197c74e8280acb3effd1949"
Dec 28 21:40:10 volumio go-librespot[7040]: time="2025-12-28T21:40:10+08:00" level=debug msg="stored credentials not found"
Dec 28 21:40:12 volumio go-librespot[7040]: time="2025-12-28T21:40:12+08:00" level=debug msg="obtained new client token: AABDpHrKXW89HCZm8EF3ULPfSxbfofmj0uWtMVWhXDQdIZB2MOj2kbgqevVatDTyzVM6Ud4dDN8G2z7b8fCBQ5mVmj9UmtpTvJKSG+rUwqZQ2TVhjgzwOTQ3ejeQAsPVHsIBmzV2uYaAuQxj+gXHvbW5rWhFNAc6tDCPnX3ZWG+FE4VD+xVCeIPFlOFQVRfYCtwVtYSHKVmmS0dAN7tF+2fuwqZW5Evu8D0ps26whvPTVAFN7zS/Lsxhrh4IzayjDg=="
Dec 28 21:40:13 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:40:13 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:13 volumio go-librespot[7040]: time="2025-12-28T21:40:13+08:00" level=debug msg="new websocket client"
Dec 28 21:40:13 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:40:14 volumio go-librespot[7040]: time="2025-12-28T21:40:14+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 28 21:40:15 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:40:15 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:15 volumio volumio[938]: info: Prefetching next song
Dec 28 21:40:15 volumio volumio[938]: info: DOING PREFETCH IN MPD
Dec 28 21:40:15 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand add "USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac"
Dec 28 21:40:15 volumio volumio[938]: info:
Dec 28 21:40:15 volumio volumio[938]: ---------------------------- MPD announces system playlist update
Dec 28 21:40:15 volumio volumio[938]: info: Ignoring MPD Status Update
Dec 28 21:40:15 volumio volumio[938]: info: sendMpdCommand add "USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac" took 2 milliseconds
Dec 28 21:40:15 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand consume 1
Dec 28 21:40:15 volumio volumio[938]: info:
Dec 28 21:40:15 volumio volumio[938]: ---------------------------- MPD announces system playlist update
Dec 28 21:40:15 volumio volumio[938]: info: Ignoring MPD Status Update
Dec 28 21:40:15 volumio volumio[938]: info:
Dec 28 21:40:15 volumio volumio[938]: ---------------------------- MPD announces system playlist update
Dec 28 21:40:15 volumio volumio[938]: info: Ignoring MPD Status Update
Dec 28 21:40:15 volumio volumio[938]: info: ------------------------------ 9ms
Dec 28 21:40:15 volumio volumio[938]: info: sendMpdCommand consume 1 took 9 milliseconds
Dec 28 21:40:15 volumio volumio[938]: info: ------------------------------ 9ms
Dec 28 21:40:15 volumio volumio[938]: info: ------------------------------ 2ms
Dec 28 21:40:16 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:40:16 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Dec 28 21:40:16 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:40:16 volumio volumio[938]: info: CorePlayQueue::getTrack 22
Dec 28 21:40:16 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:17 volumio go-librespot[7040]: time="2025-12-28T21:40:17+08:00" level=debug msg="completed keyexchange"
Dec 28 21:40:17 volumio go-librespot[7040]: time="2025-12-28T21:40:17+08:00" level=debug msg="completed challenge"
Dec 28 21:40:17 volumio go-librespot[7040]: time="2025-12-28T21:40:17+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:40:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:40:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:40:17 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: socket hang up
Dec 28 21:40:17 volumio volumio[938]: at connResetException (internal/errors.js:607:14)
Dec 28 21:40:17 volumio volumio[938]: at Socket.socketOnEnd (_http_client.js:493:23)
Dec 28 21:40:17 volumio volumio[938]: at Socket.emit (events.js:327:22)
Dec 28 21:40:17 volumio volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12)
Dec 28 21:40:17 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Dec 28 21:40:17 volumio volumio[938]: (node:938) 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: 310)
Dec 28 21:40:17 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:40:19 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:40:20 volumio volumio[938]: info: CoreStateMachine::startPlaybackTimer
Dec 28 21:40:20 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:20 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:20 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:20 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 28 21:40:20 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:20 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:20 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:20 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:40:21 volumio volumio[938]: info:
Dec 28 21:40:21 volumio volumio[938]: ---------------------------- MPD announces system playlist update
Dec 28 21:40:21 volumio volumio[938]: info: Ignoring MPD Status Update
Dec 28 21:40:21 volumio volumio[938]: info:
Dec 28 21:40:21 volumio volumio[938]: ---------------------------- MPD announces state update: player
Dec 28 21:40:21 volumio volumio[938]: info: ControllerMpd::getState
Dec 28 21:40:21 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:40:21 volumio volumio[938]: info:
Dec 28 21:40:21 volumio volumio[938]: ---------------------------- MPD announces system playlist update
Dec 28 21:40:21 volumio volumio[938]: info: Ignoring MPD Status Update
Dec 28 21:40:21 volumio volumio[938]: info:
Dec 28 21:40:21 volumio volumio[938]: ---------------------------- MPD announces state update: player
Dec 28 21:40:21 volumio volumio[938]: info: ControllerMpd::getState
Dec 28 21:40:21 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:40:21 volumio volumio[938]: info:
Dec 28 21:40:21 volumio volumio[938]: ---------------------------- MPD announces system playlist update
Dec 28 21:40:21 volumio volumio[938]: info: Ignoring MPD Status Update
Dec 28 21:40:21 volumio volumio[938]: info:
Dec 28 21:40:21 volumio volumio[938]: ---------------------------- MPD announces state update: player
Dec 28 21:40:21 volumio volumio[938]: info: ControllerMpd::getState
Dec 28 21:40:21 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:40:21 volumio volumio[938]: info: ------------------------------ 7ms
Dec 28 21:40:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:40:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 307.
Dec 28 21:40:21 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:40:21 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:40:21 volumio go-librespot[7051]: Librespot-go daemon starting...
Dec 28 21:40:21 volumio go-librespot[7051]: time="2025-12-28T21:40:21+08:00" level=info msg="generated new device id: e1df47bc57ed9a1b4e7c50c7adfeffd86ab4d57f"
Dec 28 21:40:21 volumio go-librespot[7051]: time="2025-12-28T21:40:21+08:00" level=debug msg="stored credentials not found"
Dec 28 21:40:21 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
Dec 28 21:40:22 volumio volumio[938]: info:
Dec 28 21:40:22 volumio volumio[938]: ---------------------------- MPD announces state update: player
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand status took 1036 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::getState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 1036ms
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand status took 1036 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 1036ms
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand status took 1034 milliseconds
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:40:22 volumio volumio[938]: info:
Dec 28 21:40:22 volumio volumio[938]: ---------------------------- MPD announces state update: player
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::getState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:40:22 volumio volumio[938]: info:
Dec 28 21:40:22 volumio volumio[938]: ---------------------------- MPD announces state update: player
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::getState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:40:22 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
Dec 28 21:40:22 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
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand status took 4 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand playlistinfo took 4 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand status took 2 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand status took 3 milliseconds
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseState
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::servicePushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"一生爱你千百回","artist":"[51ape.com]梅艳芳","album":null,"uri":"USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac","trackType":"flac"}
Dec 28 21:40:22 volumio volumio[938]: verbose: CURRENT POSITION 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState stateService play
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:40:22 volumio volumio[938]: info: Received an update from plugin. extracting info from payload
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::servicePushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"一生爱你千百回","artist":"[51ape.com]梅艳芳","album":null,"uri":"USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac","trackType":"flac"}
Dec 28 21:40:22 volumio volumio[938]: verbose: CURRENT POSITION 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState stateService play
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:40:22 volumio volumio[938]: info: Received an update from plugin. extracting info from payload
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::servicePushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"一生爱你千百回","artist":"[51ape.com]梅艳芳","album":null,"uri":"USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac","trackType":"flac"}
Dec 28 21:40:22 volumio volumio[938]: verbose: CURRENT POSITION 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState stateService play
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:40:22 volumio volumio[938]: info: Received an update from plugin. extracting info from payload
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 1093ms
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 1091ms
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand playlistinfo took 52 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand playlistinfo took 49 milliseconds
Dec 28 21:40:22 volumio volumio[938]: info: sendMpdCommand playlistinfo took 49 milliseconds
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:40:22 volumio volumio[938]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::servicePushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"一生爱你千百回","artist":"[51ape.com]梅艳芳","album":null,"uri":"USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac","trackType":"flac"}
Dec 28 21:40:22 volumio volumio[938]: verbose: CURRENT POSITION 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState stateService play
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:40:22 volumio volumio[938]: info: Received an update from plugin. extracting info from payload
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::servicePushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"一生爱你千百回","artist":"[51ape.com]梅艳芳","album":null,"uri":"USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac","trackType":"flac"}
Dec 28 21:40:22 volumio volumio[938]: verbose: CURRENT POSITION 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState stateService play
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:40:22 volumio volumio[938]: info: Received an update from plugin. extracting info from payload
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: ControllerMpd::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::servicePushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"一生爱你千百回","artist":"[51ape.com]梅艳芳","album":null,"uri":"USB/9E8C15E78C15BB2B/音乐/梅艳芳 - 一生爱你千百回.flac","trackType":"flac"}
Dec 28 21:40:22 volumio volumio[938]: verbose: CURRENT POSITION 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState stateService play
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:40:22 volumio volumio[938]: info: Received an update from plugin. extracting info from payload
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: CoreStateMachine::pushState
Dec 28 21:40:22 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:22 volumio volumio[938]: info: CoreCommandRouter::volumioPushState
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 116ms
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 114ms
Dec 28 21:40:22 volumio volumio[938]: info: ------------------------------ 114ms
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:22 volumio go-librespot[7051]: time="2025-12-28T21:40:22+08:00" level=debug msg="obtained new client token: AADjkkUCW3fGB25M6I+O+fwuRkuHcIiJafE4EhywS5CHUX1OC9Dch6ETgpYH9YNci2EMjoL9JuWMIp/37WaLDl7j9SjV4cKti00OouBao/e/GhXBxypJ7bMGnkOkD0qM9XWsKP6rBXG696GmDuNF8JcioO7ppWunbEQZbzOHG70aWAhbfpQVfHap27Nh5MLrwGL5wdDyVaqZzFnx0aWgvTuFTO/IncyfT6snMpJt53FJ4H13PMAE3MGdtfd0HxuLkw=="
Dec 28 21:40:23 volumio go-librespot[7051]: time="2025-12-28T21:40: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 28 21:40:23 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:23 volumio go-librespot[7051]: time="2025-12-28T21:40:23+08:00" level=debug msg="new websocket client"
Dec 28 21:40:23 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:40:25 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:40:25 volumio kernel: hwmon hwmon1: Undervoltage detected!
Dec 28 21:40:26 volumio go-librespot[7051]: time="2025-12-28T21:40:26+08:00" level=debug msg="completed keyexchange"
Dec 28 21:40:26 volumio go-librespot[7051]: time="2025-12-28T21:40:26+08:00" level=debug msg="completed challenge"
Dec 28 21:40:26 volumio go-librespot[7051]: time="2025-12-28T21:40:26+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:40:26 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:40:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:40:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:40:26 volumio volumio[938]: info: Getting Spotify volume
Dec 28 21:40:27 volumio volumio[938]: (node:938) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:40:27 volumio volumio[938]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Dec 28 21:40:27 volumio volumio[938]: (node:938) 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: 311)
Dec 28 21:40:27 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Dec 28 21:40:27 volumio volumio[938]: info: CoreCommandRouter::volumioGetState
Dec 28 21:40:27 volumio volumio[938]: info: CorePlayQueue::getTrack 23
Dec 28 21:40:27 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Dec 28 21:40:29 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:29 volumio volumio[938]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 28 21:40:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Dec 28 21:40:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 308.
Dec 28 21:40:30 volumio systemd[1]: Stopped go-librespot Daemon.
Dec 28 21:40:30 volumio systemd[1]: Started go-librespot Daemon.
Dec 28 21:40:30 volumio go-librespot[7074]: Librespot-go daemon starting...
Dec 28 21:40:30 volumio go-librespot[7074]: time="2025-12-28T21:40:30+08:00" level=info msg="generated new device id: e9187a1b71e44f32feb2ed5b1ec1539ddf57a3a5"
Dec 28 21:40:30 volumio go-librespot[7074]: time="2025-12-28T21:40:30+08:00" level=debug msg="stored credentials not found"
Dec 28 21:40:31 volumio wpa_supplicant[774]: wlan0: Failed to initiate sched scan
Dec 28 21:40:31 volumio go-librespot[7074]: time="2025-12-28T21:40:31+08:00" level=debug msg="obtained new client token: AAB/7ED9uK78DPPkrK7n+XbmxR60ZpF5j+4SZGGljW1tjUYLdoJH1XRLcHH9SPsH3UUmQ2XX/Lk0PHiQTC97u319dp4Y3vqVyrVFbjzxs7MSP11VM7eDTASB5TShIW0kgEQDoRJ0pEQ9QFNH/fpJ2+dA7H3rZf2XuQOXfLUFo4Ktg8b3exyHFpJCqHsj58Ynos0t1+qvF1VCJP9FXBAG7/wmVdeWWL0F05wi9rEeG2O5vq1HjZoKOCQUYOxT0o0="
Dec 28 21:40:32 volumio volumio[938]: info: Initializing connection to go-librespot Websocket
Dec 28 21:40:32 volumio go-librespot[7074]: time="2025-12-28T21:40:32+08:00" level=debug msg="new websocket client"
Dec 28 21:40:32 volumio volumio[938]: info: Connection to go-librespot Websocket established
Dec 28 21:40:33 volumio go-librespot[7074]: time="2025-12-28T21:40:33+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 28 21:40:34 volumio go-librespot[7074]: time="2025-12-28T21:40:34+08:00" level=debug msg="completed keyexchange"
Dec 28 21:40:34 volumio go-librespot[7074]: time="2025-12-28T21:40:34+08:00" level=debug msg="completed challenge"
Dec 28 21:40:35 volumio go-librespot[7074]: time="2025-12-28T21:40:35+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials"
Dec 28 21:40:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 28 21:40:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 28 21:40:35 volumio volumio[938]: info: Connection to go-librespot Websocket closed
Dec 28 21:40:35 volumio volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 28 21:40:35 volumio volumio[938]: Error: connect ETIMEDOUT 157.240.15.8:80
Dec 28 21:40:35 volumio volumio[938]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Dec 28 21:40:35 volumio volumio[938]: errno: -110,
Dec 28 21:40:35 volumio volumio[938]: code: 'ETIMEDOUT',
Dec 28 21:40:35 volumio volumio[938]: syscall: 'connect',
Dec 28 21:40:35 volumio volumio[938]: address: '157.240.15.8',
Dec 28 21:40:35 volumio volumio[938]: port: 80
Dec 28 21:40:35 volumio volumio[938]: }
Dec 28 21:40:35 volumio volumio[938]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 28 21:40:35 volumio sudo[7093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 21:39
Dec 28 21:40:35 volumio sudo[7093]: 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"