Feb 12 15:11:06 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:14 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:20 volumio-new-2026 sudo[21787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 15:11:20 volumio-new-2026 sudo[21787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:11:20 volumio-new-2026 sudo[21787]: pam_unix(sudo:session): session closed for user root Feb 12 15:11:20 volumio-new-2026 sudo[21789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 15:11:20 volumio-new-2026 sudo[21789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:11:20 volumio-new-2026 sudo[21789]: pam_unix(sudo:session): session closed for user root Feb 12 15:11:20 volumio-new-2026 volumio[9718]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.250 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 12 15:11:20 volumio-new-2026 sudo[21793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 15:11:20 volumio-new-2026 sudo[21793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:11:20 volumio-new-2026 sudo[21793]: pam_unix(sudo:session): session closed for user root Feb 12 15:11:20 volumio-new-2026 sudo[21795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 15:11:20 volumio-new-2026 sudo[21795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:11:20 volumio-new-2026 sudo[21795]: pam_unix(sudo:session): session closed for user root Feb 12 15:11:20 volumio-new-2026 volumio[9718]: verbose: New Socket.io Connection to 192.168.1.197 from 192.168.1.250 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetQueue Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreStateMachine::getQueue Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getQueue Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: Listing playlists Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: Received Get System Info Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: Discovery: Getting this device information Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 15:11:20 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: Received Get System Info Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: Discovery: Getting this device information Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: Received Get System Info Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: Discovery: Getting this device information Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:11:26 volumio-new-2026 go-librespot[9945]: time="2026-02-12T15:11:26-05:00" level=trace msg="sent dealer ping" Feb 12 15:11:26 volumio-new-2026 go-librespot[9945]: time="2026-02-12T15:11:26-05:00" level=trace msg="received dealer pong" Feb 12 15:11:30 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 12 15:11:30 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:32 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:32 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:34 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:11:34 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 15:11:34 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 15:11:38 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: Received Get System Version Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: Received Get System Info Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: Discovery: Getting this device information Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:39 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:11:43 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: Received Get System Info Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: Discovery: Getting this device information Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:11:46 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:53 volumio-new-2026 volumio[9718]: error: error Feb 12 15:11:53 volumio-new-2026 volumio[9718]: error: error Feb 12 15:11:55 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:11:56 volumio-new-2026 go-librespot[9945]: time="2026-02-12T15:11:56-05:00" level=trace msg="sent dealer ping" Feb 12 15:11:56 volumio-new-2026 go-librespot[9945]: time="2026-02-12T15:11:56-05:00" level=trace msg="received dealer pong" Feb 12 15:12:03 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:12:11 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:12:18 volumio-new-2026 volumio[9718]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 12 15:12:18 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 12 15:12:18 volumio-new-2026 volumio[9718]: info: Creating Spotify config file Feb 12 15:12:18 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 15:12:18 volumio-new-2026 volumio[9718]: info: Spotify config file written Feb 12 15:12:18 volumio-new-2026 sudo[21889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 15:12:18 volumio-new-2026 sudo[21889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:18 volumio-new-2026 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 15:12:18 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Killing process 10083 (go-librespot) with signal SIGKILL. Feb 12 15:12:18 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 15:12:18 volumio-new-2026 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:18 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Consumed 5min 24.222s CPU time. Feb 12 15:12:18 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket closed Feb 12 15:12:18 volumio-new-2026 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:18 volumio-new-2026 go-librespot[21891]: go-librespot daemon starting... Feb 12 15:12:18 volumio-new-2026 sudo[21889]: pam_unix(sudo:session): session closed for user root Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=info msg="running go-librespot 0.6.2" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=debug msg="app state loaded" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=info msg="zeroconf server listening on port 46029" Feb 12 15:12:18 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:18-05:00" level=debug msg="obtained new client token: AAD7GjDNr2j+ueyBxtOdpwRjlDOrjkGccZsOLETFRpXXBIvoD5MW321yeSqTfPDiuDlSdSIecEvqFQWJqBS8f/TpN/hXXsjJ+dgCa9xEfJ7Nl4CPlRE9d8kALbdWFRFyob8Jom6hODXijowgcLqnVOx4Xd72SsMfLFPqpwqRBpS1jYjYydiFz726Au6SCJMB44qZYRYDNE3aDjsxnURmWOtLwgyjdT6ZHjurAeeowCSwrl5vO9SxAwJ82A==" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="completed keyexchange" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="completed challenge" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=info msg="authenticated AP" username="de********th" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=info msg="authenticated Login5" username="de********th" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="initializing zeroconf session" username="de********th" Feb 12 15:12:19 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="dealer connection opened" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=trace msg="starting accesspoint recv loop" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=trace msg="starting dealer recv loop" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=trace msg="received accesspoint ping" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="received connection id: YmU1NTg5ZDgtZjg4...NzlGRjYxNjk3Qg==" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=trace msg="received accesspoint pong ack" Feb 12 15:12:19 volumio-new-2026 go-librespot[21892]: time="2026-02-12T15:12:19-05:00" level=debug msg="put connect state because NEW_DEVICE" Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: Creating Spotify config file Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: Spotify config file written Feb 12 15:12:21 volumio-new-2026 sudo[21905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 15:12:21 volumio-new-2026 sudo[21905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:21 volumio-new-2026 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 15:12:21 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Killing process 21903 (go-librespot) with signal SIGKILL. Feb 12 15:12:21 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 15:12:21 volumio-new-2026 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:21 volumio-new-2026 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:21 volumio-new-2026 go-librespot[21907]: go-librespot daemon starting... Feb 12 15:12:21 volumio-new-2026 sudo[21905]: pam_unix(sudo:session): session closed for user root Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=info msg="running go-librespot 0.6.2" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="app state loaded" Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: Initializing connection to go-librespot Websocket Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 15:12:21 volumio-new-2026 volumio[9718]: info: go-librespot daemon successfully initialized Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=info msg="zeroconf server listening on port 35599" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="obtained new client token: AACSo7LoUQXR2r4EZNWFeeg3CzoxmvjI8miyhvGD+7kSqrnT3YYDRGymPjIXNFZVpb9DPSuqCcuaCVOwGmsTkMAhefPRO0yPzDC2OkFj3uyNDIzC+GS39gO1rb5Us/ZggrRyRAyNO7uM1Qx3SIkpOPJK34m/mVcv/NWch28lIIPfZJb84nYogi1sglZTKSJ87aVyRfc6HsYG8N3GfSxXQYilBB8wEcgPWDJCxFXVDFNTic/rlB/F2K2j/w==" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="completed keyexchange" Feb 12 15:12:21 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:21-05:00" level=debug msg="completed challenge" Feb 12 15:12:22 volumio-new-2026 go-librespot[21908]: time="2026-02-12T15:12:22-05:00" level=info msg="authenticated AP" username="de********th" Feb 12 15:12:22 volumio-new-2026 volumio[9718]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 12 15:12:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 12 15:12:22 volumio-new-2026 volumio[9718]: info: Creating Spotify config file Feb 12 15:12:22 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 15:12:22 volumio-new-2026 volumio[9718]: info: Spotify config file written Feb 12 15:12:22 volumio-new-2026 sudo[21919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 15:12:22 volumio-new-2026 sudo[21919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:22 volumio-new-2026 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 15:12:22 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Killing process 21917 (go-librespot) with signal SIGKILL. Feb 12 15:12:22 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 15:12:22 volumio-new-2026 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:22 volumio-new-2026 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:22 volumio-new-2026 go-librespot[21921]: go-librespot daemon starting... Feb 12 15:12:22 volumio-new-2026 sudo[21919]: pam_unix(sudo:session): session closed for user root Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=info msg="running go-librespot 0.6.2" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=debug msg="app state loaded" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05: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]" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05: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]" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05: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]" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=info msg="zeroconf server listening on port 37843" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=debug msg="obtained new client token: AABuTBIvhIYi4HS73QvzKXjYpa6TyHp46dTLq1B0PriLEfG8CQhCfyGT7Dx3NW9c+eR19zLkfCyidV6pkA9s55bGpVio4/gRYIUj7H41ho3CUFpiTTI49gMTxQxCMQOMHoeLlhw6BKDCQp2uAypeGPRKrrJ7HjhHrXOR6slrAcT7lVb1jsZx3H5lHz20sQuhpVLuI82z1tJiGs8aV3upLWehMH8A4/slfAECqplCrPBt2q/jQlv4euyjmQ==" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=debug msg="completed keyexchange" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=debug msg="completed challenge" Feb 12 15:12:22 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:22-05:00" level=info msg="authenticated AP" username="de********th" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=info msg="authenticated Login5" username="de********th" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="initializing zeroconf session" username="de********th" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="dealer connection opened" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=trace msg="starting accesspoint recv loop" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=trace msg="starting dealer recv loop" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=trace msg="received accesspoint ping" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="received connection id: MmJlMTg4YzAtODhm...NEY3ODA5QTU4RQ==" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=trace msg="received accesspoint pong ack" Feb 12 15:12:23 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:23-05:00" level=debug msg="put connect state because NEW_DEVICE" Feb 12 15:12:24 volumio-new-2026 volumio[9718]: info: go-librespot daemon successfully initialized Feb 12 15:12:24 volumio-new-2026 volumio[9718]: info: Initializing connection to go-librespot Websocket Feb 12 15:12:24 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:24-05:00" level=debug msg="new websocket client" Feb 12 15:12:24 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket established Feb 12 15:12:24 volumio-new-2026 volumio[9718]: info: Initializing connection to go-librespot Websocket Feb 12 15:12:24 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:24-05:00" level=debug msg="new websocket client" Feb 12 15:12:24 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket established Feb 12 15:12:25 volumio-new-2026 volumio[9718]: info: go-librespot daemon successfully initialized Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Initializing connection to go-librespot Websocket Feb 12 15:12:27 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:27-05:00" level=debug msg="new websocket client" Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket established Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Getting Spotify volume Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Spotify volume: 100 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Getting Spotify volume Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Spotify volume: 100 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: SPOTIFY: SPOTIFY VOLUME 100 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: SPOTIFY: VOLUMIO VOLUME 45 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Setting Spotify Volume from Volumio: 45 Feb 12 15:12:27 volumio-new-2026 volumio[9718]: info: Discovery: Remote connection error: 192.168.1.181 Feb 12 15:12:28 volumio-new-2026 volumio[9718]: info: Initializing connection to go-librespot Websocket Feb 12 15:12:28 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:28-05:00" level=debug msg="new websocket client" Feb 12 15:12:28 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket established Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Feb 12 15:12:29 volumio-new-2026 volumio[9718]: info: Sending Spotify command with payload to local API: /player/volume Feb 12 15:12:29 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:29-05:00" level=debug msg="update volume requested to 29490/65535" Feb 12 15:12:29 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:29-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 12 15:12:29 volumio-new-2026 go-librespot[21922]: time="2026-02-12T15:12:29-05:00" level=trace msg="emitting websocket event: volume" Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Feb 12 15:12:29 volumio-new-2026 volumio[9718]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Getting Spotify volume Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Spotify volume: 45 Feb 12 15:12:30 volumio-new-2026 volumio[9718]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Creating Spotify config file Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::volumioGetState Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CorePlayQueue::getTrack 3 Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Spotify config file written Feb 12 15:12:30 volumio-new-2026 volumio[9718]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Feb 12 15:12:30 volumio-new-2026 sudo[21947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 15:12:30 volumio-new-2026 sudo[21947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:30 volumio-new-2026 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 15:12:30 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Killing process 21926 (go-librespot) with signal SIGKILL. Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket closed Feb 12 15:12:30 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 15:12:30 volumio-new-2026 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket closed Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket closed Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Connection to go-librespot Websocket closed Feb 12 15:12:30 volumio-new-2026 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:30 volumio-new-2026 go-librespot[21949]: go-librespot daemon starting... Feb 12 15:12:30 volumio-new-2026 sudo[21947]: pam_unix(sudo:session): session closed for user root Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05:00" level=info msg="running go-librespot 0.6.2" Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05:00" level=debug msg="app state loaded" Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Creating Spotify config file Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 15:12:30 volumio-new-2026 volumio[9718]: info: Spotify config file written Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 15:12:30 volumio-new-2026 sudo[21960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 15:12:30 volumio-new-2026 sudo[21960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:30 volumio-new-2026 go-librespot[21950]: time="2026-02-12T15:12:30-05:00" level=info msg="zeroconf server listening on port 33135" Feb 12 15:12:30 volumio-new-2026 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 15:12:30 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Killing process 21956 (go-librespot) with signal SIGKILL. Feb 12 15:12:30 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 15:12:30 volumio-new-2026 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:31 volumio-new-2026 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:31 volumio-new-2026 go-librespot[21962]: go-librespot daemon starting... Feb 12 15:12:31 volumio-new-2026 sudo[21960]: pam_unix(sudo:session): session closed for user root Feb 12 15:12:31 volumio-new-2026 go-librespot[21963]: time="2026-02-12T15:12:31-05:00" level=info msg="running go-librespot 0.6.2" Feb 12 15:12:31 volumio-new-2026 go-librespot[21963]: time="2026-02-12T15:12:31-05:00" level=debug msg="app state loaded" Feb 12 15:12:31 volumio-new-2026 go-librespot[21963]: time="2026-02-12T15:12:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 15:12:31 volumio-new-2026 volumio[9718]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 12 15:12:31 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 12 15:12:31 volumio-new-2026 volumio[9718]: info: Creating Spotify config file Feb 12 15:12:31 volumio-new-2026 volumio[9718]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 15:12:31 volumio-new-2026 volumio[9718]: info: Spotify config file written Feb 12 15:12:31 volumio-new-2026 sudo[21972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 15:12:31 volumio-new-2026 sudo[21972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:31 volumio-new-2026 volumio[9718]: info: Getting Spotify volume Feb 12 15:12:31 volumio-new-2026 volumio[9718]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 12 15:12:31 volumio-new-2026 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 15:12:31 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Killing process 21968 (go-librespot) with signal SIGKILL. Feb 12 15:12:31 volumio-new-2026 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 15:12:31 volumio-new-2026 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:31 volumio-new-2026 volumio[9718]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 15:12:31 volumio-new-2026 volumio[9718]: Error: socket hang up Feb 12 15:12:31 volumio-new-2026 volumio[9718]: at connResetException (node:internal/errors:720:14) Feb 12 15:12:31 volumio-new-2026 volumio[9718]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 12 15:12:31 volumio-new-2026 volumio[9718]: at Socket.emit (node:events:526:35) Feb 12 15:12:31 volumio-new-2026 volumio[9718]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 12 15:12:31 volumio-new-2026 volumio[9718]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 12 15:12:31 volumio-new-2026 volumio[9718]: code: 'ECONNRESET', Feb 12 15:12:31 volumio-new-2026 volumio[9718]: response: undefined Feb 12 15:12:31 volumio-new-2026 volumio[9718]: } Feb 12 15:12:31 volumio-new-2026 volumio[9718]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 15:12:31 volumio-new-2026 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 15:12:31 volumio-new-2026 go-librespot[21975]: go-librespot daemon starting... Feb 12 15:12:31 volumio-new-2026 sudo[21972]: pam_unix(sudo:session): session closed for user root Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=info msg="running go-librespot 0.6.2" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="app state loaded" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05: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-gew1.spotify.com:80]" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=info msg="zeroconf server listening on port 39153" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="obtained new client token: AABLxnT7HmgfDglC/VA4q/GSc4HoAq18wKOBIGAHV0z2s6+j9FCbrO0oimdP9bYli5GTFpbJNIhK0q7JYeB7QONAhlBGwphDkuI3gSjT9ZaP0OZBGJmUR/ezk6jHk05ygb1mLAIBBzReE4hLRUATBK9ZBzevsLmanbHHIR/nYX5jYi+XbUhtnxpgDGSdlAQ4ZdBRKqbm+R8n1H+YzZttx/UmNnuHnQWGLj4hBL8ls24PQwVsYLNw/HO6eg==" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="completed keyexchange" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=debug msg="completed challenge" Feb 12 15:12:31 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:31-05:00" level=info msg="authenticated AP" username="de********th" Feb 12 15:12:32 volumio-new-2026 sudo[21999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 15:11' Feb 12 15:12:32 volumio-new-2026 sudo[21999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:12:32 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:32-05:00" level=info msg="authenticated Login5" username="de********th" Feb 12 15:12:32 volumio-new-2026 go-librespot[21987]: time="2026-02-12T15:12:32-05:00" level=debug msg="initializing zeroconf session" username="de********th" 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"