Feb 07 12:30:06 altanen kernel: hwmon hwmon1: Voltage normalised Feb 07 12:30:08 altanen go-librespot[4164]: time="2026-02-07T12:30:08+01:00" level=trace msg="sent dealer ping" Feb 07 12:30:08 altanen go-librespot[4164]: time="2026-02-07T12:30:08+01:00" level=trace msg="received dealer pong" Feb 07 12:30:10 altanen kernel: hwmon hwmon1: Undervoltage detected! Feb 07 12:30:16 altanen kernel: hwmon hwmon1: Voltage normalised Feb 07 12:30:20 altanen kernel: hwmon hwmon1: Undervoltage detected! Feb 07 12:30:24 altanen kernel: hwmon hwmon1: Voltage normalised Feb 07 12:30:31 altanen kernel: hwmon hwmon1: Undervoltage detected! Feb 07 12:30:35 altanen kernel: hwmon hwmon1: Voltage normalised Feb 07 12:30:38 altanen go-librespot[4164]: time="2026-02-07T12:30:38+01:00" level=trace msg="sent dealer ping" Feb 07 12:30:38 altanen go-librespot[4164]: time="2026-02-07T12:30:38+01:00" level=trace msg="received dealer pong" Feb 07 12:30:41 altanen kernel: hwmon hwmon1: Undervoltage detected! Feb 07 12:30:45 altanen kernel: hwmon hwmon1: Voltage normalised Feb 07 12:30:49 altanen kernel: hwmon hwmon1: Undervoltage detected! Feb 07 12:30:56 altanen kernel: hwmon hwmon1: Voltage normalised Feb 07 12:31:00 altanen kernel: hwmon hwmon1: Undervoltage detected! Feb 07 12:31:02 altanen volumio[3147]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 07 12:31:02 altanen volumio[3147]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 07 12:31:02 altanen volumio[3147]: info: Creating Spotify config file Feb 07 12:31:02 altanen volumio[3147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 12:31:02 altanen volumio[3147]: info: Spotify config file written Feb 07 12:31:02 altanen sudo[4321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 12:31:02 altanen sudo[4321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 12:31:02 altanen systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 12:31:02 altanen systemd[1]: go-librespot-daemon.service: Killing process 4167 (go-librespot) with signal SIGKILL. Feb 07 12:31:02 altanen volumio[3147]: info: Connection to go-librespot Websocket closed Feb 07 12:31:02 altanen systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 12:31:02 altanen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 12:31:02 altanen volumio[3147]: info: Connection to go-librespot Websocket closed Feb 07 12:31:02 altanen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 12:31:02 altanen go-librespot[4323]: go-librespot daemon starting... Feb 07 12:31:02 altanen sudo[4321]: pam_unix(sudo:session): session closed for user root Feb 07 12:31:02 altanen go-librespot[4324]: time="2026-02-07T12:31:02+01:00" level=info msg="running go-librespot 0.6.2" Feb 07 12:31:02 altanen go-librespot[4324]: time="2026-02-07T12:31:02+01:00" level=debug msg="app state loaded" Feb 07 12:31:02 altanen go-librespot[4324]: time="2026-02-07T12:31:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=info msg="zeroconf server listening on port 43499" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="obtained new client token: AABZ3r5z618v0PzonKk5ScgqnqI2gB7Q+2I8cYBCbigLGBQS1qgCcvCItjwx8T9oHwnLU0JTeoxYBWZ3G5PDGMMGLxtS+2lctKxlb+MzCGEhfbkY0L9dwHhz7o8NsSJrJkkJyTg92TdnZqSF9kyOltL034SxfXL3exZ58L1wyg2V7dFrbgZfC3GZmQVTUESPU6wlHpvnCvHGLeakuzcS4R4x0FbF+BDWTtMjcbllfj/aBBjY6CH9wQ==" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="completed keyexchange" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="completed challenge" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=info msg="authenticated AP" username="ju*****00" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=info msg="authenticated Login5" username="ju*****00" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="initializing zeroconf session" username="ju*****00" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="dealer connection opened" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=trace msg="starting accesspoint recv loop" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=trace msg="starting dealer recv loop" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=trace msg="received accesspoint ping" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=debug msg="received connection id: OTc1OTY2OTEtZmI5...Q0UyMTg3NENBQg==" Feb 07 12:31:03 altanen go-librespot[4324]: time="2026-02-07T12:31:03+01:00" level=trace msg="received accesspoint pong ack" Feb 07 12:31:04 altanen go-librespot[4324]: time="2026-02-07T12:31:04+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 07 12:31:05 altanen volumio[3147]: info: Initializing connection to go-librespot Websocket Feb 07 12:31:05 altanen go-librespot[4324]: time="2026-02-07T12:31:05+01:00" level=debug msg="new websocket client" Feb 07 12:31:05 altanen volumio[3147]: info: Connection to go-librespot Websocket established Feb 07 12:31:05 altanen volumio[3147]: info: go-librespot daemon successfully initialized Feb 07 12:31:07 altanen volumio[3147]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 07 12:31:07 altanen volumio[3147]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 07 12:31:07 altanen volumio[3147]: info: Creating Spotify config file Feb 07 12:31:07 altanen volumio[3147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 12:31:07 altanen volumio[3147]: info: Spotify config file written Feb 07 12:31:08 altanen sudo[4334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 12:31:08 altanen sudo[4334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 12:31:08 altanen systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 12:31:08 altanen systemd[1]: go-librespot-daemon.service: Killing process 4327 (go-librespot) with signal SIGKILL. Feb 07 12:31:08 altanen volumio[3147]: info: Connection to go-librespot Websocket closed Feb 07 12:31:08 altanen systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 12:31:08 altanen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 12:31:08 altanen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 12:31:08 altanen go-librespot[4336]: go-librespot daemon starting... Feb 07 12:31:08 altanen sudo[4334]: pam_unix(sudo:session): session closed for user root Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=info msg="running go-librespot 0.6.2" Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=debug msg="app state loaded" Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 12:31:08 altanen volumio[3147]: info: Getting Spotify volume Feb 07 12:31:08 altanen volumio[3147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 07 12:31:08 altanen volumio[3147]: info: Initializing connection to go-librespot Websocket Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=debug msg="new websocket client" Feb 07 12:31:08 altanen volumio[3147]: info: Connection to go-librespot Websocket established Feb 07 12:31:08 altanen volumio[3147]: info: CoreCommandRouter::volumioGetState Feb 07 12:31:08 altanen volumio[3147]: info: CorePlayQueue::getTrack 0 Feb 07 12:31:08 altanen volumio[3147]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 12:31:08 altanen go-librespot[4337]: time="2026-02-07T12:31:08+01:00" level=info msg="zeroconf server listening on port 37635" Feb 07 12:31:08 altanen volumio[3147]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 07 12:31:08 altanen volumio[3147]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 07 12:31:08 altanen volumio[3147]: info: Creating Spotify config file Feb 07 12:31:08 altanen volumio[3147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 12:31:08 altanen volumio[3147]: info: Spotify config file written Feb 07 12:31:08 altanen sudo[4347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 12:31:08 altanen sudo[4347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 12:31:08 altanen systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 12:31:08 altanen systemd[1]: go-librespot-daemon.service: Killing process 4345 (go-librespot) with signal SIGKILL. Feb 07 12:31:08 altanen systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 12:31:08 altanen systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 12:31:08 altanen volumio[3147]: info: Connection to go-librespot Websocket closed Feb 07 12:31:08 altanen volumio[3147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 12:31:08 altanen volumio[3147]: Error: socket hang up Feb 07 12:31:08 altanen volumio[3147]: at connResetException (node:internal/errors:720:14) Feb 07 12:31:08 altanen volumio[3147]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 12:31:08 altanen volumio[3147]: at Socket.emit (node:events:526:35) Feb 07 12:31:08 altanen volumio[3147]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 12:31:08 altanen volumio[3147]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 12:31:08 altanen volumio[3147]: code: 'ECONNRESET', Feb 07 12:31:08 altanen volumio[3147]: response: undefined Feb 07 12:31:08 altanen volumio[3147]: } Feb 07 12:31:08 altanen volumio[3147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 12:31:08 altanen systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 12:31:08 altanen go-librespot[4349]: go-librespot daemon starting... Feb 07 12:31:09 altanen sudo[4347]: pam_unix(sudo:session): session closed for user root Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=info msg="running go-librespot 0.6.2" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=debug msg="app state loaded" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=info msg="zeroconf server listening on port 40967" Feb 07 12:31:09 altanen go-librespot[4353]: time="2026-02-07T12:31:09+01:00" level=debug msg="obtained new client token: AAChLj7LNzdDiNyK2Kq1JtN9aOm1BgnSWHv7C9eRc/xOYbmAivh8PXxZygNNCU/OpkYa3y83hHIHKMtyQHSmL2eoEGhMV4qRZJ0Pwv7eEnujOMVl6Zygepihje/hC4381dGVWQZi0MU7vUmcuUDpP0iMltOqjQpljWtzFJmVf7P8Hh5ATVuykfDvp+8fdeqbd2bgaX5NO5YCb03fisMAn2yW2OD7jEDiJW6TzSW8ggoJricTuS4kcfGt" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="completed keyexchange" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="completed challenge" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=info msg="authenticated AP" username="ju*****00" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=info msg="authenticated Login5" username="ju*****00" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="initializing zeroconf session" username="ju*****00" Feb 07 12:31:10 altanen sudo[4388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 12:30' Feb 07 12:31:10 altanen sudo[4388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="dealer connection opened" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=trace msg="starting accesspoint recv loop" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=trace msg="starting dealer recv loop" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=trace msg="received accesspoint ping" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 07 12:31:10 altanen go-librespot[4353]: time="2026-02-07T12:31:10+01:00" level=debug msg="received connection id: ODExZWE0MTktYTc2...MEE2NDI1RDBBNQ==" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"