Apr 03 19:13:09 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:09 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:14 volumio go-librespot[4739]: time="2026-04-03T19:13:14-06:00" level=trace msg="sent dealer ping" Apr 03 19:13:14 volumio go-librespot[4739]: time="2026-04-03T19:13:14-06:00" level=trace msg="received dealer pong" Apr 03 19:13:19 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:19 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:19 volumio volumio[1023]: info: Listing playlists Apr 03 19:13:19 volumio volumio[1023]: info: Listing playlists Apr 03 19:13:29 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:29 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:39 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:39 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:39 volumio volumio[1023]: info: Listing playlists Apr 03 19:13:39 volumio volumio[1023]: info: Listing playlists Apr 03 19:13:44 volumio go-librespot[4739]: time="2026-04-03T19:13:44-06:00" level=trace msg="sent dealer ping" Apr 03 19:13:44 volumio go-librespot[4739]: time="2026-04-03T19:13:44-06:00" level=trace msg="received dealer pong" Apr 03 19:13:49 volumio volumio[1023]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 03 19:13:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 03 19:13:49 volumio volumio[1023]: info: Creating Spotify config file Apr 03 19:13:49 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 19:13:49 volumio volumio[1023]: info: Spotify config file written Apr 03 19:13:49 volumio sudo[4939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 03 19:13:49 volumio sudo[4939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 19:13:49 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 03 19:13:49 volumio volumio[1023]: info: Connection to go-librespot Websocket closed Apr 03 19:13:49 volumio systemd[1]: go-librespot-daemon.service: Killing process 4747 (go-librespot) with signal SIGKILL. Apr 03 19:13:49 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 03 19:13:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 19:13:49 volumio volumio[1023]: info: Connection to go-librespot Websocket closed Apr 03 19:13:49 volumio systemd[1]: go-librespot-daemon.service: Consumed 1.064s CPU time. Apr 03 19:13:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 19:13:49 volumio go-librespot[4941]: go-librespot daemon starting... Apr 03 19:13:49 volumio sudo[4939]: pam_unix(sudo:session): session closed for user root Apr 03 19:13:49 volumio go-librespot[4942]: time="2026-04-03T19:13:49-06:00" level=info msg="running go-librespot 0.7.1" Apr 03 19:13:49 volumio go-librespot[4942]: time="2026-04-03T19:13:49-06:00" level=debug msg="app state loaded" Apr 03 19:13:49 volumio go-librespot[4942]: time="2026-04-03T19:13:49-06:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 19:13:49 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:49 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=info msg="zeroconf server listening on port 37017" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="obtained new client token: AABXOB5wDOwYFXKBA2oVUOOxQfHgC/JaPQofDbvQ1hptls9EYp7W2I5PmXI/aZkjVvW4tdFRQMmlC/QYRB/4h4bztbBxzy4Hljqmf+a0lcaUZ8hzxKga4JGWSllRs74wBSF2HIMXzleVAWJP3QS5lJFskxsn1xBsdlswYnOOvKGT7AlwnJjgf8GvGnPUEM+iYS/uoIDL9taIWrfKLD3VVT40M1STAFQ9S9W7Zkvfh0HRabO+8F90dOM=" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="completed keyexchange" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=debug msg="completed challenge" Apr 03 19:13:50 volumio go-librespot[4942]: time="2026-04-03T19:13:50-06:00" level=info msg="authenticated AP" username="31************************s4" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=info msg="authenticated Login5" username="31************************s4" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="initializing zeroconf session" username="31************************s4" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="dealer connection opened" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=trace msg="starting accesspoint recv loop" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=trace msg="starting dealer recv loop" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=trace msg="received accesspoint ping" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="received connection id: ODEwYTNkNmQtOTZi...MjU0Mzk5N0NFQg==" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=trace msg="received accesspoint pong ack" Apr 03 19:13:51 volumio go-librespot[4942]: time="2026-04-03T19:13:51-06:00" level=debug msg="put connect state because NEW_DEVICE" Apr 03 19:13:52 volumio volumio[1023]: info: Initializing connection to go-librespot Websocket Apr 03 19:13:52 volumio go-librespot[4942]: time="2026-04-03T19:13:52-06:00" level=debug msg="new websocket client" Apr 03 19:13:52 volumio volumio[1023]: info: Connection to go-librespot Websocket established Apr 03 19:13:52 volumio volumio[1023]: info: go-librespot daemon successfully initialized Apr 03 19:13:55 volumio volumio[1023]: info: Getting Spotify volume Apr 03 19:13:55 volumio volumio[1023]: info: Spotify volume: 100 Apr 03 19:13:55 volumio volumio[1023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 03 19:13:55 volumio volumio[1023]: info: Initializing connection to go-librespot Websocket Apr 03 19:13:55 volumio go-librespot[4942]: time="2026-04-03T19:13:55-06:00" level=debug msg="new websocket client" Apr 03 19:13:55 volumio volumio[1023]: info: Connection to go-librespot Websocket established Apr 03 19:13:55 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:55 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:58 volumio volumio[1023]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 03 19:13:58 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 03 19:13:58 volumio volumio[1023]: info: Creating Spotify config file Apr 03 19:13:58 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 19:13:58 volumio volumio[1023]: info: Spotify config file written Apr 03 19:13:58 volumio sudo[4967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 03 19:13:58 volumio sudo[4967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 19:13:58 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 03 19:13:58 volumio systemd[1]: go-librespot-daemon.service: Killing process 4951 (go-librespot) with signal SIGKILL. Apr 03 19:13:58 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 03 19:13:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 19:13:58 volumio volumio[1023]: info: Connection to go-librespot Websocket closed Apr 03 19:13:58 volumio volumio[1023]: info: Connection to go-librespot Websocket closed Apr 03 19:13:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 19:13:58 volumio sudo[4967]: pam_unix(sudo:session): session closed for user root Apr 03 19:13:58 volumio go-librespot[4969]: go-librespot daemon starting... Apr 03 19:13:58 volumio go-librespot[4970]: time="2026-04-03T19:13:58-06:00" level=info msg="running go-librespot 0.7.1" Apr 03 19:13:58 volumio go-librespot[4970]: time="2026-04-03T19:13:58-06:00" level=debug msg="app state loaded" Apr 03 19:13:58 volumio go-librespot[4970]: time="2026-04-03T19:13:58-06:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 19:13:58 volumio volumio[1023]: info: Getting Spotify volume Apr 03 19:13:58 volumio volumio[1023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 03 19:13:58 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Apr 03 19:13:58 volumio volumio[1023]: info: CorePlayQueue::getTrack 612 Apr 03 19:13:58 volumio volumio[1023]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 03 19:13:58 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 03 19:13:58 volumio volumio[1023]: info: Creating Spotify config file Apr 03 19:13:58 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 19:13:58 volumio volumio[1023]: info: Spotify config file written Apr 03 19:13:59 volumio sudo[4980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 03 19:13:59 volumio sudo[4980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 19:13:59 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 03 19:13:59 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 03 19:13:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 19:13:59 volumio volumio[1023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 19:13:59 volumio volumio[1023]: Error: socket hang up Apr 03 19:13:59 volumio volumio[1023]: at connResetException (node:internal/errors:720:14) Apr 03 19:13:59 volumio volumio[1023]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 03 19:13:59 volumio volumio[1023]: at Socket.emit (node:events:526:35) Apr 03 19:13:59 volumio volumio[1023]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 03 19:13:59 volumio volumio[1023]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 03 19:13:59 volumio volumio[1023]: code: 'ECONNRESET', Apr 03 19:13:59 volumio volumio[1023]: response: undefined Apr 03 19:13:59 volumio volumio[1023]: } Apr 03 19:13:59 volumio volumio[1023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 19:13:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 19:13:59 volumio go-librespot[4982]: go-librespot daemon starting... Apr 03 19:13:59 volumio sudo[4980]: pam_unix(sudo:session): session closed for user root Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=info msg="running go-librespot 0.7.1" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=debug msg="app state loaded" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=info msg="zeroconf server listening on port 40573" Apr 03 19:13:59 volumio go-librespot[4986]: time="2026-04-03T19:13:59-06:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=debug msg="obtained new client token: AABYiBQNPQXu/5uWai1wBtuQ1juq/RNCjtCNWCQKdVBlaWd0+gy2wOlyV4PkfpqcpFu4Vawh9Wj2KzV8C55XdhkeYP3zSKgt3QXlQfSVEgeQ0v/W721CWO95iV6HTATKk2kg/q1HBwetw/zhZ/ZFyHaQaG3AOm0JnWqPVL7D/D5wUaJFnOzJlzx+ezWHkEp4YLf6zjT0wT7NgjPJEq9xffgmpR4CvjRnl4d3NvhczzKQzKDn15Ep" Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=debug msg="completed keyexchange" Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=debug msg="completed challenge" Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=info msg="authenticated AP" username="31************************s4" Apr 03 19:14:00 volumio sudo[5006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-03 19:13' Apr 03 19:14:00 volumio sudo[5006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=info msg="authenticated Login5" username="31************************s4" Apr 03 19:14:00 volumio go-librespot[4986]: time="2026-04-03T19:14:00-06:00" level=debug msg="initializing zeroconf session" username="31************************s4" 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"