Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Received OAUTH Data Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Executing Spotify Oauth Login Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Saving Spotify Refresh Token Apr 11 12:07:46 lounge-speakers volumio[1304]: info: New Spotify access tokenBQC12iq-_E... Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 11 12:07:46 lounge-speakers sudo[4119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 12:07:46 lounge-speakers sudo[4121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 12:07:46 lounge-speakers sudo[4121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:07:46 lounge-speakers sudo[4119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:07:46 lounge-speakers sudo[4121]: pam_unix(sudo:session): session closed for user root Apr 11 12:07:46 lounge-speakers sudo[4119]: pam_unix(sudo:session): session closed for user root Apr 11 12:07:46 lounge-speakers volumio[1304]: verbose: New Socket.io Connection to 192.168.1.251 from 192.168.1.199 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Pro XL Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Received Get System Info Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Discovery: Getting this device information Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Listing playlists Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 12:07:46 lounge-speakers volumio[1304]: SPOTIFY: User informations: {"country":"GB","display_name":"Elliott Andrews","email":"kiezack7@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1121205227"},"followers":{"href":null,"total":8},"href":"https://api.spotify.com/v1/users/1121205227","id":"1121205227","images":[{"height":300,"url":"https://scontent-cdg6-1.xx.fbcdn.net/v/t39.30808-1/486187246_10226689125685574_8628055060936767371_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=106&ccb=1-7&_nc_sid=08baa4&_nc_ohc=undPxeLYf34Q7kNvwEmrx4M&_nc_oc=AdoibhNuobgixMez9XiG6MxwChFOr7twH61JnqcWPgECiLA2up1dOLYj9MEsbSUgiN2ggDsGBqa3TL1CEJbQIY3j&_nc_zt=24&_nc_ht=scontent-cdg6-1.xx&edm=AP4hL3IEAAAA&_nc_gid=CnTeihdsGRxvMMBvOkcQlQ&_nc_tpa=Q5bMBQHsrHDD5GbnmdkyerIu25T4VpZI9M0BoY9PJnl_kdIDvz2A45K3DAYONiYOb-FTjorRUB37&oh=00_Af3HmPiIR0wPT3vxZQ2qzz_V0_mj0qSxwHMlnbEPMgbDPA&oe=69E00C1E","width":300},{"height":64,"url":"https://scontent-cdg6-1.xx.fbcdn.net/v/t39.30808-1/486187246_10226689125685574_8628055060936767371_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=undPxeLYf34Q7kNvwEmrx4M&_nc_oc=AdoibhNuobgixMez9XiG6MxwChFOr7twH61JnqcWPgECiLA2up1dOLYj9MEsbSUgiN2ggDsGBqa3TL1CEJbQIY3j&_nc_zt=24&_nc_ht=scontent-cdg6-1.xx&edm=AP4hL3IEAAAA&_nc_gid=CnTeihdsGRxvMMBvOkcQlQ&_nc_tpa=Q5bMBQHuGi2YsBpjxLne5K2-AiDUld7mXCya9-bvUSGZ7fc7o_bXdTq8pknHCYlVEYdGzyC6hvzh&oh=00_Af2Z-QXjc5EH2yEqM8mHN7PaTNYA2BQNbsaKNyWLKO9jqQ&oe=69E00C1E","width":64}],"product":"premium","type":"user","uri":"spotify:user:1121205227"} Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:07:46 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:07:46 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:07:46 lounge-speakers sudo[4126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:07:46 lounge-speakers sudo[4126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:07:47 lounge-speakers systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 11 12:07:47 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:07:47 lounge-speakers systemd[1]: go-librespot-daemon.service: Killing process 3961 (go-librespot) with signal SIGKILL. Apr 11 12:07:47 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:07:47 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:07:47 lounge-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:07:47 lounge-speakers go-librespot[4130]: go-librespot daemon starting... Apr 11 12:07:47 lounge-speakers sudo[4126]: pam_unix(sudo:session): session closed for user root Apr 11 12:07:47 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket closed Apr 11 12:07:47 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket closed Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=debug msg="app state loaded" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=debug msg="stored credentials not found" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:07:47 lounge-speakers volumio[1304]: info: New Spotify access tokenBQACO96fvy... Apr 11 12:07:47 lounge-speakers volumio[1304]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 11 12:07:47 lounge-speakers volumio[1304]: SPOTIFY: User informations: {"country":"GB","display_name":"Elliott Andrews","email":"kiezack7@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1121205227"},"followers":{"href":null,"total":8},"href":"https://api.spotify.com/v1/users/1121205227","id":"1121205227","images":[{"height":300,"url":"https://scontent-cdg6-1.xx.fbcdn.net/v/t39.30808-1/486187246_10226689125685574_8628055060936767371_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=106&ccb=1-7&_nc_sid=08baa4&_nc_ohc=undPxeLYf34Q7kNvwEmrx4M&_nc_oc=AdoibhNuobgixMez9XiG6MxwChFOr7twH61JnqcWPgECiLA2up1dOLYj9MEsbSUgiN2ggDsGBqa3TL1CEJbQIY3j&_nc_zt=24&_nc_ht=scontent-cdg6-1.xx&edm=AP4hL3IEAAAA&_nc_gid=CnTeihdsGRxvMMBvOkcQlQ&_nc_tpa=Q5bMBQHsrHDD5GbnmdkyerIu25T4VpZI9M0BoY9PJnl_kdIDvz2A45K3DAYONiYOb-FTjorRUB37&oh=00_Af3HmPiIR0wPT3vxZQ2qzz_V0_mj0qSxwHMlnbEPMgbDPA&oe=69E00C1E","width":300},{"height":64,"url":"https://scontent-cdg6-1.xx.fbcdn.net/v/t39.30808-1/486187246_10226689125685574_8628055060936767371_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=undPxeLYf34Q7kNvwEmrx4M&_nc_oc=AdoibhNuobgixMez9XiG6MxwChFOr7twH61JnqcWPgECiLA2up1dOLYj9MEsbSUgiN2ggDsGBqa3TL1CEJbQIY3j&_nc_zt=24&_nc_ht=scontent-cdg6-1.xx&edm=AP4hL3IEAAAA&_nc_gid=CnTeihdsGRxvMMBvOkcQlQ&_nc_tpa=Q5bMBQHuGi2YsBpjxLne5K2-AiDUld7mXCya9-bvUSGZ7fc7o_bXdTq8pknHCYlVEYdGzyC6hvzh&oh=00_Af2Z-QXjc5EH2yEqM8mHN7PaTNYA2BQNbsaKNyWLKO9jqQ&oe=69E00C1E","width":64}],"product":"premium","type":"user","uri":"spotify:user:1121205227"} Apr 11 12:07:47 lounge-speakers volumio[1304]: info: Spotify Successfully logged in Apr 11 12:07:47 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 12:07:47 lounge-speakers volumio[1304]: info: [1775905667495] CoreMusicLibrary::Adding element Spotify Apr 11 12:07:47 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 12:07:47 lounge-speakers volumio[1304]: Cannot find translation for source Spotify Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=info msg="zeroconf server listening on port 38145" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=debug msg="obtained new client token: AAB0ipR4PSwwW4CqYnvvgNRX+fu2ESkh4HFQ/Fa1abxOshsv68BNC/8cUDJSpr17g+BNcewlEv0Mv3yB39rPqkMUgu4ufIJ0lY1vXBRaC3pf3vYNyESv5yAgy+sl8mtu8RVDMrAyUl06e1Z/d//Eh5Qaqtx+uwgt0jSZ7UEWQvtdOodgQQQ3J8spK2vkXYmLsxWgKZqdIsdigoZHCxmx8ROdJwo60KFdfwvFvr55CeR/UDAWl9RX/jo=" Apr 11 12:07:47 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:47+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="completed keyexchange" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="completed challenge" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=info msg="authenticated AP" username="11******27" Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 12:07:48 lounge-speakers volumio[1304]: info: Received Get System Info Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 12:07:48 lounge-speakers volumio[1304]: info: Discovery: Getting this device information Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:48 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=info msg="authenticated Login5" username="11******27" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="stored credentials" username="11******27" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="initializing zeroconf session" username="11******27" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="dealer connection opened" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=trace msg="starting accesspoint recv loop" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=trace msg="starting dealer recv loop" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=trace msg="received accesspoint ping" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=debug msg="received connection id: NjlkNjkxNDgtOWZi...QjczQ0E3RUNFMg==" Apr 11 12:07:48 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:48+01:00" level=trace msg="received accesspoint pong ack" Apr 11 12:07:49 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:49+01:00" level=debug msg="put connect state because NEW_DEVICE" Apr 11 12:07:49 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 12:07:49 lounge-speakers volumio[1304]: info: Received Get System Info Apr 11 12:07:49 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 12:07:49 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 12:07:49 lounge-speakers volumio[1304]: info: Discovery: Getting this device information Apr 11 12:07:49 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:49 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:49 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 12:07:49 lounge-speakers systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 11 12:07:49 lounge-speakers systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 11 12:07:49 lounge-speakers systemd[1]: setdatetime-helper.service: Consumed 1.250s CPU time. Apr 11 12:07:50 lounge-speakers volumio[1304]: info: Initializing connection to go-librespot Websocket Apr 11 12:07:50 lounge-speakers volumio[1304]: info: go-librespot daemon successfully initialized Apr 11 12:07:50 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:50+01:00" level=debug msg="new websocket client" Apr 11 12:07:50 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket established Apr 11 12:07:53 lounge-speakers volumio[1304]: info: Initializing connection to go-librespot Websocket Apr 11 12:07:53 lounge-speakers volumio[1304]: info: Getting Spotify volume Apr 11 12:07:53 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:53+01:00" level=debug msg="new websocket client" Apr 11 12:07:53 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket established Apr 11 12:07:53 lounge-speakers volumio[1304]: info: Spotify volume: 100 Apr 11 12:07:53 lounge-speakers volumio[1304]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 11 12:07:53 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:53 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:53 lounge-speakers volumio[1304]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Apr 11 12:07:53 lounge-speakers volumio[1304]: SPOTIFY: SPOTIFY VOLUME 100 Apr 11 12:07:53 lounge-speakers volumio[1304]: SPOTIFY: VOLUMIO VOLUME 86 Apr 11 12:07:53 lounge-speakers volumio[1304]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 11 12:07:53 lounge-speakers volumio[1304]: info: Setting Spotify Volume from Volumio: 86 Apr 11 12:07:54 lounge-speakers volumio[1304]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Apr 11 12:07:54 lounge-speakers volumio[1304]: info: Sending Spotify command with payload to local API: /player/volume Apr 11 12:07:54 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:54+01:00" level=debug msg="update volume requested to 56360/65535" Apr 11 12:07:54 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:54+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 11 12:07:54 lounge-speakers go-librespot[4132]: time="2026-04-11T12:07:54+01:00" level=trace msg="emitting websocket event: volume" Apr 11 12:07:54 lounge-speakers volumio[1304]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Apr 11 12:07:54 lounge-speakers volumio[1304]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Apr 11 12:07:54 lounge-speakers volumio[1304]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Apr 11 12:07:54 lounge-speakers volumio[1304]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Apr 11 12:07:56 lounge-speakers volumio[1304]: info: Getting Spotify volume Apr 11 12:07:56 lounge-speakers volumio[1304]: info: Spotify volume: 86 Apr 11 12:07:56 lounge-speakers volumio[1304]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 11 12:07:56 lounge-speakers volumio[1304]: info: CoreCommandRouter::volumioGetState Apr 11 12:07:56 lounge-speakers volumio[1304]: info: CorePlayQueue::getTrack 0 Apr 11 12:07:56 lounge-speakers volumio[1304]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Apr 11 12:07:56 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 11 12:07:57 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:07:57 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:07:57 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:07:57 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:07:57 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:07:57 lounge-speakers sudo[4180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:07:57 lounge-speakers sudo[4180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:07:57 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:07:57 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:07:57 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket closed Apr 11 12:07:57 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:07:57 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket closed Apr 11 12:07:57 lounge-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:07:57 lounge-speakers go-librespot[4182]: go-librespot daemon starting... Apr 11 12:07:57 lounge-speakers sudo[4180]: pam_unix(sudo:session): session closed for user root Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=debug msg="app state loaded" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=info msg="zeroconf server listening on port 41593" Apr 11 12:07:57 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:57+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="obtained new client token: AAD/Jv7C/ukNNzluTq/JAJLGthGtU6MxHZvJq+isDwI07IIBEqsq17m9fSuTbKJAAlnmQheeOcbPiLTX/x4Icm6yWniX07lIBuZnpCF728PHIFdwBSKKIsH4zc9EXNbStBXx7aDEAwpjHLFff6GPZo9pbjfBx3y+WqO60mCK7bJIS9TzzriSuCfzxXurfcqccN6VSWqcK0qWNikrBIjrja5TlHVaOfLYBD4+kZ3kUCqxqDuIVdFt" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="completed keyexchange" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="completed challenge" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=info msg="authenticated AP" username="11******27" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=info msg="authenticated Login5" username="11******27" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="initializing zeroconf session" username="11******27" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="dealer connection opened" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=trace msg="starting accesspoint recv loop" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=trace msg="starting dealer recv loop" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=trace msg="received accesspoint ping" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=debug msg="received connection id: N2NmMDUzNTUtYzU0...QjI1Q0U3NjY3RA==" Apr 11 12:07:58 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:58+01:00" level=trace msg="received accesspoint pong ack" Apr 11 12:07:59 lounge-speakers go-librespot[4185]: time="2026-04-11T12:07:59+01:00" level=debug msg="put connect state because NEW_DEVICE" Apr 11 12:07:59 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:07:59 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:07:59 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:07:59 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:07:59 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:07:59 lounge-speakers sudo[4196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:07:59 lounge-speakers sudo[4196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:07:59 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:07:59 lounge-speakers systemd[1]: go-librespot-daemon.service: Killing process 4194 (go-librespot) with signal SIGKILL. Apr 11 12:07:59 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:07:59 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:07:59 lounge-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:07:59 lounge-speakers go-librespot[4198]: go-librespot daemon starting... Apr 11 12:07:59 lounge-speakers sudo[4196]: pam_unix(sudo:session): session closed for user root Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=debug msg="app state loaded" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=info msg="zeroconf server listening on port 36089" Apr 11 12:07:59 lounge-speakers go-librespot[4199]: time="2026-04-11T12:07:59+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="obtained new client token: AABLwM2DMzwaElOa2gSB7a18HDvKJw5POyyCwwbkmtWe1ZNvay40WfauEQcc88aGY6B83qtIpQUUFuHZFc5qYgkmc6eDD55kqPChMM+OIxXFdVj9blmW8HEC6JqO721nLQULlHJlS4C1KH1O3kCx4V1yZsMXeftHphCrrrG0QRuDLf/DSk8gdnjNLMhw5dnwiq9tass/CNdTaFjMB5ncOE5viIsuA7ajVMsXsj9LCPfkwTE+DN0X" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="completed keyexchange" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="completed challenge" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=info msg="authenticated AP" username="11******27" Apr 11 12:08:00 lounge-speakers volumio[1304]: info: Initializing connection to go-librespot Websocket Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="new websocket client" Apr 11 12:08:00 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket established Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=info msg="authenticated Login5" username="11******27" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="initializing zeroconf session" username="11******27" Apr 11 12:08:00 lounge-speakers volumio[1304]: info: go-librespot daemon successfully initialized Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="dealer connection opened" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=trace msg="starting accesspoint recv loop" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=trace msg="starting dealer recv loop" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=trace msg="received accesspoint ping" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="received connection id: M2I0MzNlYzQtZWEw...MjM0QTkyNTVCOA==" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=trace msg="received accesspoint pong ack" Apr 11 12:08:00 lounge-speakers go-librespot[4199]: time="2026-04-11T12:08:00+01:00" level=debug msg="put connect state because NEW_DEVICE" Apr 11 12:08:01 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:01 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:01 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:01 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:01 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:01 lounge-speakers sudo[4209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:01 lounge-speakers sudo[4209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:01 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:08:01 lounge-speakers systemd[1]: go-librespot-daemon.service: Killing process 4204 (go-librespot) with signal SIGKILL. Apr 11 12:08:01 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:08:01 lounge-speakers volumio[1304]: info: Connection to go-librespot Websocket closed Apr 11 12:08:01 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:01 lounge-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:01 lounge-speakers go-librespot[4211]: go-librespot daemon starting... Apr 11 12:08:01 lounge-speakers sudo[4209]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=debug msg="app state loaded" Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:08:01 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:01 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:01 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:01 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:01 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:01 lounge-speakers sudo[4222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:01 lounge-speakers sudo[4222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 11 12:08:01 lounge-speakers go-librespot[4212]: time="2026-04-11T12:08:01+01:00" level=info msg="zeroconf server listening on port 44939" Apr 11 12:08:01 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:08:02 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers go-librespot[4224]: go-librespot daemon starting... Apr 11 12:08:02 lounge-speakers sudo[4222]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:02 lounge-speakers go-librespot[4225]: time="2026-04-11T12:08:02+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:08:02 lounge-speakers go-librespot[4225]: time="2026-04-11T12:08:02+01:00" level=debug msg="app state loaded" Apr 11 12:08:02 lounge-speakers sudo[4232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers sudo[4232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:02 lounge-speakers go-librespot[4225]: time="2026-04-11T12:08:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:08:02 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Killing process 4230 (go-librespot) with signal SIGKILL. Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:08:02 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers sudo[4232]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:02 lounge-speakers go-librespot[4236]: go-librespot daemon starting... Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=info msg="running go-librespot 0.7.1" Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=debug msg="app state loaded" Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 12:08:02 lounge-speakers volumio[1304]: info: go-librespot daemon successfully initialized Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=info msg="zeroconf server listening on port 39055" Apr 11 12:08:02 lounge-speakers go-librespot[4237]: time="2026-04-11T12:08:02+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:02 lounge-speakers sudo[4247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers sudo[4247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:02 lounge-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Killing process 4240 (go-librespot) with signal SIGKILL. Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 12:08:02 lounge-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Apr 11 12:08:02 lounge-speakers systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers sudo[4247]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:02 lounge-speakers volumio[1304]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:02 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:02 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:02 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:02 lounge-speakers volumio[1304]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:02 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:02 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:02 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:02 lounge-speakers sudo[4250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers sudo[4250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Apr 11 12:08:02 lounge-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Apr 11 12:08:02 lounge-speakers systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:02 lounge-speakers sudo[4250]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:02 lounge-speakers volumio[1304]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:02 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:02 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:02 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:02 lounge-speakers volumio[1304]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:02 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:02 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:02 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:02 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:02 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:02 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:03 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:03 lounge-speakers sudo[4253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:03 lounge-speakers sudo[4253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:03 lounge-speakers volumio[1304]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Apr 11 12:08:03 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Apr 11 12:08:03 lounge-speakers volumio[1304]: info: Creating Spotify config file Apr 11 12:08:03 lounge-speakers volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 12:08:03 lounge-speakers volumio[1304]: info: Spotify config file written Apr 11 12:08:03 lounge-speakers systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Apr 11 12:08:03 lounge-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Apr 11 12:08:03 lounge-speakers systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:03 lounge-speakers sudo[4253]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:03 lounge-speakers volumio[1304]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:03 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:03 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:03 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:03 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:03 lounge-speakers volumio[1304]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:03 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:03 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:03 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:03 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:03 lounge-speakers sudo[4256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 12:08:03 lounge-speakers sudo[4256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 12:08:03 lounge-speakers systemd[1]: go-librespot-daemon.service: Start request repeated too quickly. Apr 11 12:08:03 lounge-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'start-limit-hit'. Apr 11 12:08:03 lounge-speakers systemd[1]: Failed to start go-librespot-daemon.service - go-librespot Daemon. Apr 11 12:08:03 lounge-speakers sudo[4256]: pam_unix(sudo:session): session closed for user root Apr 11 12:08:03 lounge-speakers volumio[1304]: error: Cannot start Go-librespot Daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:03 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:03 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:03 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:03 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:03 lounge-speakers volumio[1304]: error: Error initializing go-librespot daemon: Error: Command failed: /usr/bin/sudo systemctl restart go-librespot-daemon.service Apr 11 12:08:03 lounge-speakers volumio[1304]: Job for go-librespot-daemon.service failed because start of the service was attempted too often. Apr 11 12:08:03 lounge-speakers volumio[1304]: See "systemctl status go-librespot-daemon.service" and "journalctl -xeu go-librespot-daemon.service" for details. Apr 11 12:08:03 lounge-speakers volumio[1304]: To force a start use "systemctl reset-failed go-librespot-daemon.service" Apr 11 12:08:03 lounge-speakers volumio[1304]: followed by "systemctl start go-librespot-daemon.service" again. Apr 11 12:08:03 lounge-speakers volumio[1304]: info: Getting Spotify volume Apr 11 12:08:03 lounge-speakers volumio[1304]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 12:08:03 lounge-speakers volumio[1304]: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 11 12:08:03 lounge-speakers volumio[1304]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 11 12:08:03 lounge-speakers volumio[1304]: errno: -111, Apr 11 12:08:03 lounge-speakers volumio[1304]: code: 'ECONNREFUSED', Apr 11 12:08:03 lounge-speakers volumio[1304]: syscall: 'connect', Apr 11 12:08:03 lounge-speakers volumio[1304]: address: '127.0.0.1', Apr 11 12:08:03 lounge-speakers volumio[1304]: port: 9879, Apr 11 12:08:03 lounge-speakers volumio[1304]: response: undefined Apr 11 12:08:03 lounge-speakers volumio[1304]: } Apr 11 12:08:03 lounge-speakers volumio[1304]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 12:08:04 lounge-speakers sudo[4272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 12:07' Apr 11 12:08:04 lounge-speakers sudo[4272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"