Aug 30 10:50:00 lazzaroaudio-mediaplayer sudo[2207]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 10:50:00 lazzaroaudio-mediaplayer sudo[2207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:50:00 lazzaroaudio-mediaplayer sudo[2207]: pam_unix(sudo:session): session closed for user root Aug 30 10:50:00 lazzaroaudio-mediaplayer sudo[2209]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 10:50:00 lazzaroaudio-mediaplayer sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:50:00 lazzaroaudio-mediaplayer sudo[2209]: pam_unix(sudo:session): session closed for user root Aug 30 10:50:03 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:50:03 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:03.972+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.142:48760,00:00:00:00:00:00%01 @ 0x2ec6a80" latency=78.159935ms timeout=10s endpoint=http://pushupdates.volumio.org duration=9.999998691s error="Head \"http://pushupdates.volumio.org\": context deadline exceeded" Aug 30 10:50:03 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:03.984+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.20.142:48760,00:00:00:00:00:00%01 @ 0x2ec6a80" latency=48.109295ms timeout=10s from=APP_PAGE_SETUP_V1_INTERNET to=APP_PAGE_LEGACY_DEVICE Aug 30 10:50:06 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Aug 30 10:50:06 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:06 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:06 lazzaroaudio-mediaplayer upmpdcli[2227]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:50:06 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:06 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:50:08 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:50:08 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:50:08 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:50:09 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:09.058+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.20.142:48760 error="read tcp 192.168.20.223:7331->192.168.20.142:48760: read: connection reset by peer" Aug 30 10:50:09 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:09.058+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.20.142:48760 Aug 30 10:50:09 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:09.058+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.20.142:48760 Aug 30 10:50:13 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:50:18 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:50:18 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:50:18 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:50:19 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: WNM: Preferred List Available Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:19.942+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address=192.168.20.223/24 ip6Address= ssid=Vodafone-Flea Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:50:19 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:19.999+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address=192.168.20.223/24 ip6Address= ssid=Vodafone-Flea Aug 30 10:50:20 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:20.988+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:50:21 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Aug 30 10:50:21 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:21 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:21 lazzaroaudio-mediaplayer upmpdcli[2264]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:50:21 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:21 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:50:23 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:50:28 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:50:28 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:50:28 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:50:33 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:50:36 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9. Aug 30 10:50:36 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:36 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:36 lazzaroaudio-mediaplayer upmpdcli[2294]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:50:36 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:36 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:50:38 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:50:38 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:50:38 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:50:43 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:50:46 lazzaroaudio-mediaplayer volumio[1216]: info: Received OAUTH Data Aug 30 10:50:46 lazzaroaudio-mediaplayer volumio[1216]: info: Executing Spotify Oauth Login Aug 30 10:50:46 lazzaroaudio-mediaplayer volumio[1216]: info: Saving Spotify Refresh Token Aug 30 10:50:46 lazzaroaudio-mediaplayer volumio[1216]: info: New Spotify access tokenBQDTg00H-7... Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: User informations: {"account_id":"vEK4rUH04s","country":"IT","display_name":"11122169907","email":"allazitt@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/11122169907"},"followers":{"href":null,"total":16},"href":"https://api.spotify.com/v1/users/11122169907","id":"11122169907","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85203df50c0af505f9d45a9cd9","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82203df50c0af505f9d45a9cd9","width":64}],"product":"premium","type":"user","uri":"spotify:user:11122169907"} Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: Creating Spotify config file Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: Spotify config file written Aug 30 10:50:47 lazzaroaudio-mediaplayer sudo[2314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 10:50:47 lazzaroaudio-mediaplayer sudo[2314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:50:47 lazzaroaudio-mediaplayer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 30 10:50:47 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 30 10:50:47 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: Connection to go-librespot Websocket closed Aug 30 10:50:47 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:47 lazzaroaudio-mediaplayer go-librespot[2316]: go-librespot daemon starting... Aug 30 10:50:47 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:47+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:50:47 lazzaroaudio-mediaplayer sudo[2314]: pam_unix(sudo:session): session closed for user root Aug 30 10:50:47 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:47+02:00" level=debug msg="app state loaded" Aug 30 10:50:47 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: New Spotify access tokenBQAq52qxMs... Aug 30 10:50:47 lazzaroaudio-mediaplayer volumio[1216]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=info msg="zeroconf server listening on port 43117" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="obtained new client token: AAETjGplxqMUfFlVY5QvglrEddDII7MsxZFc1oNoVF0LoDbEXkbhQZ/Vivj/N48QzfXw/LyHd9bNmZaPfEeJSYapy9GLmrNRZYCqAiLLGi0D6VkUMBSLMMm3hxGLVCHYY7vF8XTNzA/kpzx006r2xq5ikxD6QdAZGp/rILmitOZ6mmTQlz3RDWxo8/Ih1HgD0M0KDB06QYWOHVi+YMOmB/uX4mywQBRd4t43AefY8LM4hiZl1/dtVMP7" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="completed keyexchange" Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=debug msg="completed challenge" Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: User informations: {"account_id":"vEK4rUH04s","country":"IT","display_name":"11122169907","email":"allazitt@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/11122169907"},"followers":{"href":null,"total":16},"href":"https://api.spotify.com/v1/users/11122169907","id":"11122169907","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85203df50c0af505f9d45a9cd9","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82203df50c0af505f9d45a9cd9","width":64}],"product":"premium","type":"user","uri":"spotify:user:11122169907"} Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: info: Spotify Successfully logged in Aug 30 10:50:48 lazzaroaudio-mediaplayer go-librespot[2317]: time="2026-08-30T10:50:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:50:48 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: info: [1788079848726] CoreMusicLibrary::Adding element Spotify Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 10:50:48 lazzaroaudio-mediaplayer volumio[1216]: Cannot find translation for source Spotify Aug 30 10:50:48 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:50:49 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-DISCONNECTED bssid=6c:63:f8:53:78:e6 reason=34 Aug 30 10:50:49 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: carrier lost Aug 30 10:50:49 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 30 10:50:49 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=IT Aug 30 10:50:49 lazzaroaudio-mediaplayer avahi-daemon[1299]: Withdrawing address record for 192.168.20.223 on wlan0. Aug 30 10:50:49 lazzaroaudio-mediaplayer avahi-daemon[1299]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.20.223. Aug 30 10:50:49 lazzaroaudio-mediaplayer avahi-daemon[1299]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 30 10:50:49 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: deleting route to 192.168.20.0/24 Aug 30 10:50:49 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: deleting default route via 192.168.20.1 Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: welcome.service: Deactivated successfully. Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 30 10:50:49 lazzaroaudio-mediaplayer welcome[2349]: Resolved ip:[0] Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 30 10:50:49 lazzaroaudio-mediaplayer systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:50.030+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:50.088+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: IO: Deleting interface #4 wlan0, 192.168.20.223#123, interface stats: received=86, sent=86, dropped=3, active_time=90 secs Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 44.30.181.131 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 212.45.144.3 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 217.61.62.224 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 93.57.121.105 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 195.32.70.195 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 162.159.200.1 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 172.232.208.229 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 93.94.88.50 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 204.216.214.76 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 185.157.229.254 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 95.110.254.234 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 37.247.53.178 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 185.19.184.35 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 89.46.74.148 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 162.159.200.123 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 172.232.209.103 unlink local addr 192.168.20.223 -> Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: go-librespot daemon successfully initialized Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:50:50 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:50.932+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:50:51 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 30 10:50:51 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:52 lazzaroaudio-mediaplayer go-librespot[2354]: go-librespot daemon starting... Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 10. Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:50:52 lazzaroaudio-mediaplayer go-librespot[2355]: time="2026-08-30T10:50:52+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:50:52 lazzaroaudio-mediaplayer go-librespot[2355]: time="2026-08-30T10:50:52+02:00" level=debug msg="app state loaded" Aug 30 10:50:52 lazzaroaudio-mediaplayer upmpdcli[2356]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:50:52 lazzaroaudio-mediaplayer go-librespot[2355]: time="2026-08-30T10:50:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:50:52 lazzaroaudio-mediaplayer go-librespot[2355]: time="2026-08-30T10:50:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:52 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:50:52 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: Trying to associate with 6c:63:f8:53:78:e7 (SSID='Vodafone-Flea' freq=5200 MHz) Aug 30 10:50:52 lazzaroaudio-mediaplayer wpa_supplicant[1176]: FT: Invalid key management type (2) Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:52.623+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:50:52 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:52.668+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 30 10:50:52 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 30 10:50:52 lazzaroaudio-mediaplayer wpa_supplicant[1176]: BSSID 6c:63:f8:53:78:e7 ignore list count incremented to 2, ignoring for 10 seconds Aug 30 10:50:53 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: Trying to associate with 6c:63:f8:53:78:e6 (SSID='Vodafone-Flea' freq=2412 MHz) Aug 30 10:50:53 lazzaroaudio-mediaplayer wpa_supplicant[1176]: FT: Invalid key management type (2) Aug 30 10:50:53 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:53.519+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:50:53 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:50:53 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: Associated with 6c:63:f8:53:78:e6 Aug 30 10:50:53 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 30 10:50:53 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Aug 30 10:50:53 lazzaroaudio-mediaplayer volumio[1216]: info: Volumio Network Manager: Network status updated: 0 Aug 30 10:50:53 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:50:53 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:54.155+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address= ip6Address= ssid=Vodafone-Flea Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:54.198+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address= ip6Address= ssid=Vodafone-Flea Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:50:54 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:50:54 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: WPA: Key negotiation completed with 6c:63:f8:53:78:e6 [PTK=CCMP GTK=CCMP] Aug 30 10:50:54 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: CTRL-EVENT-CONNECTED - Connection to 6c:63:f8:53:78:e6 completed [id=0 id_str=] Aug 30 10:50:54 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: carrier acquired Aug 30 10:50:54 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: RRM: Unexpected neighbor report Aug 30 10:50:54 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: IAID 01:79:83:79 Aug 30 10:50:54 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: probing address 192.168.20.223/24 Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:55.077+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address= ip6Address= ssid=Vodafone-Flea Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:55.081+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:55.128+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address= ip6Address= ssid=Vodafone-Flea Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:50:55 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:50:55 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 30 10:50:55 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:55 lazzaroaudio-mediaplayer go-librespot[2380]: go-librespot daemon starting... Aug 30 10:50:55 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:55 lazzaroaudio-mediaplayer go-librespot[2381]: time="2026-08-30T10:50:55+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:50:55 lazzaroaudio-mediaplayer go-librespot[2381]: time="2026-08-30T10:50:55+02:00" level=debug msg="app state loaded" Aug 30 10:50:55 lazzaroaudio-mediaplayer go-librespot[2381]: time="2026-08-30T10:50:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:50:55 lazzaroaudio-mediaplayer go-librespot[2381]: time="2026-08-30T10:50:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 30 10:50:55 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:55 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:50:55 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: soliciting an IPv6 router Aug 30 10:50:56 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:56.082+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:50:56 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:50:56 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:50:58 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:50:58 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:50:58 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:50:58 lazzaroaudio-mediaplayer go-librespot[2389]: go-librespot daemon starting... Aug 30 10:50:58 lazzaroaudio-mediaplayer go-librespot[2390]: time="2026-08-30T10:50:58+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:50:58 lazzaroaudio-mediaplayer go-librespot[2390]: time="2026-08-30T10:50:58+02:00" level=debug msg="app state loaded" Aug 30 10:50:58 lazzaroaudio-mediaplayer go-librespot[2390]: time="2026-08-30T10:50:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:50:58 lazzaroaudio-mediaplayer go-librespot[2390]: time="2026-08-30T10:50:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:50:58 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: using static address 192.168.20.223/24 Aug 30 10:50:58 lazzaroaudio-mediaplayer avahi-daemon[1299]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.20.223. Aug 30 10:50:58 lazzaroaudio-mediaplayer avahi-daemon[1299]: New relevant interface wlan0.IPv4 for mDNS. Aug 30 10:50:58 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: adding route to 192.168.20.0/24 Aug 30 10:50:58 lazzaroaudio-mediaplayer avahi-daemon[1299]: Registering new address record for 192.168.20.223 on wlan0.IPv4. Aug 30 10:50:58 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: adding default route via 192.168.20.1 Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: welcome.service: Deactivated successfully. Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 30 10:50:58 lazzaroaudio-mediaplayer systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 30 10:50:59 lazzaroaudio-mediaplayer systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 30 10:50:59 lazzaroaudio-mediaplayer welcome[2411]: Resolved ip:[1] 192.168.20.223 Aug 30 10:50:59 lazzaroaudio-mediaplayer systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 30 10:50:59 lazzaroaudio-mediaplayer systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:59.480+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address=192.168.20.223/24 ip6Address= ssid=Vodafone-Flea Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:50:59.577+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address=192.168.20.223/24 ip6Address= ssid=Vodafone-Flea Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:50:59 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:00 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:51:00.378+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:51:00 lazzaroaudio-mediaplayer ntpd[1024]: IO: Listen normally on 5 wlan0 192.168.20.223:123 Aug 30 10:51:00 lazzaroaudio-mediaplayer ntpd[1024]: IO: new interface(s) found: waking up resolver Aug 30 10:51:01 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 30 10:51:01 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:01 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:01 lazzaroaudio-mediaplayer go-librespot[2422]: go-librespot daemon starting... Aug 30 10:51:01 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:01+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:01 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:01+02:00" level=debug msg="app state loaded" Aug 30 10:51:01 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=info msg="zeroconf server listening on port 44673" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="obtained new client token: AAEIubsfKun1pou49UThowPVHGFwyREPwGsFTWziiVDCkpBufUxlpa5pMTGkoBr6GgC+TnYSc8u+sDuhj2KWDdSl8yuzEZkBj2E9vtL0b3TmLE1+DepWGbcNJpPzAxReuSk3uBEdNMOGlsDbq85oFURvOyh2pz+yuKnNxW1TaZ9SGAHpD+IyK+Ire3FjrMgB50nWmqDhTT280Syc6E1eavrK2GsoeOtdXOEw6JkUGYA1r84yKdKmN24t" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=debug msg="completed challenge" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2423]: time="2026-08-30T10:51:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:02 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:02 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: Creating Spotify config file Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: Spotify config file written Aug 30 10:51:02 lazzaroaudio-mediaplayer sudo[2433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 10:51:02 lazzaroaudio-mediaplayer sudo[2433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:51:02 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:02 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:02 lazzaroaudio-mediaplayer go-librespot[2435]: go-librespot daemon starting... Aug 30 10:51:02 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:02 lazzaroaudio-mediaplayer sudo[2433]: pam_unix(sudo:session): session closed for user root Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="app state loaded" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=info msg="zeroconf server listening on port 37239" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="obtained new client token: AAFylLuRaNf7FB731OeL4LfyGOORNBh7iJyJV5apHQt804vZlWr6O0Ml5ICrsEdr/Xk9FaZVLA3EzMA10wx7kyKYU4bKHSWtAQ1iOUimc9TqdnqnGVQbHmowHu5/HTZNJk43EeEbYpYIYFQk6nXsv86maOP+gFGLhZw9/ffE4gVSkrOs2iE/GVxW+fMuNXWtaWGBJ7HQ/8BhkPbXJeSt9ff4LIKvMz1lBLpWPcfdOI271nN9krHvtbTz" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 30 10:51:03 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=debug msg="completed challenge" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:03 lazzaroaudio-mediaplayer go-librespot[2436]: time="2026-08-30T10:51:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:03 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:03 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:03 lazzaroaudio-mediaplayer volumio[1216]: info: Volumio Network Manager: Network status updated: 2 Aug 30 10:51:04 lazzaroaudio-mediaplayer volumio[1216]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Aug 30 10:51:04 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Aug 30 10:51:04 lazzaroaudio-mediaplayer volumio[1216]: info: Creating Spotify config file Aug 30 10:51:04 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 10:51:04 lazzaroaudio-mediaplayer volumio[1216]: info: Spotify config file written Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:51:05 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:05 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2464]: go-librespot daemon starting... Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="app state loaded" Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2460]: pam_unix(sudo:session): session closed for user root Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2463]: pam_unix(sudo:session): session closed for user root Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:51:05 lazzaroaudio-mediaplayer sudo[2467]: pam_unix(sudo:session): session closed for user root Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=info msg="zeroconf server listening on port 35119" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: verbose: New Socket.io Connection to 192.168.20.223 from 192.168.20.142 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="obtained new client token: AAFdRt995QxpiK28KrKRccUdHRnuxAjEE2PCMoahPe0aJGencwD/MRbDy757zzjPPH5IoHzFF0Ij8J2ruaoItd2b0qFWE0sciAedKzz1ZmzsY3rdo3sFG8ZNUp6gl/QwZgzP+59AmRSXXRctWU5KgayWD3WqNaT7mQW5+kSb+k7iVqa7/nrx0v6AeqAAYnWwJdZzUOuhd1cRYz+CpGY9cSdb2Q/AjizF97Hsrdro3vvlvxNLmenPlX9t" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=debug msg="completed challenge" Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 10:51:05 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:05+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:05 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: Listing playlists Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: go-librespot daemon successfully initialized Aug 30 10:51:06 lazzaroaudio-mediaplayer go-librespot[2468]: time="2026-08-30T10:51:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:06 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:06 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 10:51:06 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 10:51:07 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11. Aug 30 10:51:07 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:51:07 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:51:07 lazzaroaudio-mediaplayer upmpdcli[2485]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:51:07 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:07 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:51:07 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 10:51:08 lazzaroaudio-mediaplayer volumio[1216]: info: go-librespot daemon successfully initialized Aug 30 10:51:08 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:51:08 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:51:08 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:51:09 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:09 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:09 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 30 10:51:09 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:09 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2486]: go-librespot daemon starting... Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="app state loaded" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=info msg="zeroconf server listening on port 37747" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="obtained new client token: AAFxxopurIefUrGPo51CC4IMctm34inNxemz0ePURJr970g+bh6wddsUfXNT81pU/GpOt9qCFJRzeQPgyoXbYHn07ZtJgAaqXtJ6QWM8NWsMmyP7/VZ7OBTlwpPnsVDPeGwWOtyFleHqRsswJndHmqNyC8jywgjVrhU/dlxif0ClZ7aUg0WPIu3Z/FPz8dql7+3Nlv1zOlQw+Zy6PhguEm33bbYloYrcZ03pS/HuPOCfCjeH3XMuZNSs" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=debug msg="completed challenge" Aug 30 10:51:09 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:09+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:10 lazzaroaudio-mediaplayer go-librespot[2487]: time="2026-08-30T10:51:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:10 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:10 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPlay Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index undefined Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:10 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:11 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:11 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:12 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPlay Aug 30 10:51:12 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index undefined Aug 30 10:51:12 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:12 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:13 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 30 10:51:13 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:13 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2496]: go-librespot daemon starting... Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="app state loaded" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:13 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=info msg="zeroconf server listening on port 38963" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:13 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetQueue Aug 30 10:51:13 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::getQueue Aug 30 10:51:13 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getQueue Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="obtained new client token: AAGuePA6l3MvMkmyqQq/JYgp+QV/8NnozVuYgGr2ds2ST7V7hpsJA4OpR6tV5COUMpWbNY6eGhYSQzBVxYDfT6n50cKmSzqADbYMnzo4Q5tX5Wt3IMpMkvG1CVzL0iH05XVOhhqvnUn+PsHFxCefz+k3RSZLLHigr8l1VNTotTqXnMlQfhpNx2idVPvIDorcrixxnuBMC4KaW+7KO8VvbF+oN71+gPjQf0HaPGuMLfg9E4BneNuE8nwx" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:13 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:13+02:00" level=debug msg="completed challenge" Aug 30 10:51:14 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:14+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:14 lazzaroaudio-mediaplayer go-librespot[2497]: time="2026-08-30T10:51:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:14 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:14 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:14 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:14 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:14 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 30 10:51:14 lazzaroaudio-mediaplayer volumio[1216]: info: In handleBrowseUri, curUri=spotify Aug 30 10:51:15 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:15 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:15 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:15 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:15 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 10:51:17 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 30 10:51:17 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:17 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2525]: go-librespot daemon starting... Aug 30 10:51:17 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:17 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="app state loaded" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=info msg="zeroconf server listening on port 38689" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="obtained new client token: AAFCwAiWktCY1ZuM5GfPfjMZyeQorJ0GMG94MXW4E0sPiXxU5qSK8lajlqgyU0U16htZfQe5sE4fFPs7gx95tNpmme5XhKkGtw8LPKLp9iBQ4Z6Vi0S2zElZC/W1hRmn2zTQ/k1mBlRGr3W8ri6WNKiLLaaxqhOeTESSkK+TOot1EyWH354bGWSokfyXwkH0dXVA1WMSioWaTNdIcvRi2fNRglZGw/jggW4nNVj899cxcP5IG+MU3RZt" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=debug msg="completed challenge" Aug 30 10:51:17 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:17+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:18 lazzaroaudio-mediaplayer go-librespot[2526]: time="2026-08-30T10:51:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:18 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:18 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:18 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:51:18 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:51:18 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:51:20 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:20 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:21 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 30 10:51:21 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:21 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2535]: go-librespot daemon starting... Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="app state loaded" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=info msg="zeroconf server listening on port 35451" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="obtained new client token: AAEIqINPp/FeQ/hlf1TKSsTR5eKvkTYzB1AzwuBdFTMOTr039il2DV82Nrco+Zxw+zEBD6iVv74u7nKaW69ByZi6mOk3dlfQcWVcmvTFso8IEQDaZ7SFVK+4gMau9XFcqFDQ2ibklVMyMS2V0rhLulK3dfjGALvORMV0k0KPcdazA5PrjWC1p9MGxcJB1QGR6O5HmUrXNY4LA6JGOz+lHbTcsno4OTxFZyRk9JpN0JAVGlthJ4VgTmsF" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:21 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:21+02:00" level=debug msg="completed challenge" Aug 30 10:51:22 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:22+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:22 lazzaroaudio-mediaplayer go-librespot[2536]: time="2026-08-30T10:51:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 12. Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:51:22 lazzaroaudio-mediaplayer upmpdcli[2546]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:22 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Preloading song: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Preloading song: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Preloading song: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Exploding uri spotify:track:1PtQJZVZIdWIYdARpZRDFO in service spop Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: EXPLODING URI:spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Exploding uri spotify:track:7D64ejn2d3g6QaahkYdKXH in service spop Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: EXPLODING URI:spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: info: Exploding uri spotify:track:2DW0Mowto3hrXkFBQt0nye in service spop Aug 30 10:51:22 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: EXPLODING URI:spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Version Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7D64ejn2d3g6QaahkYdKXH","service":"spop","name":"Tranquilla!","artist":"Fabrizio Vidale","album":"Oceania (Colonna Sonora Originale)","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273b6ae587113d515aa52c21112","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1PtQJZVZIdWIYdARpZRDFO","service":"spop","name":"Running Up That Hill (A Deal With God)","artist":"Kate Bush","album":"Hounds Of Love","type":"song","duration":298,"albumart":"https://i.scdn.co/image/ab67616d0000b273ad08f4b38efbff0c0da0f252","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2DW0Mowto3hrXkFBQt0nye","service":"spop","name":"Chicken Banana","artist":"Crazy Music Channel","album":"Chicken Banana","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c786b539e61217e5bdbde16","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::ClearQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::clearPlayQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPlay Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index 0 Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index undefined Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::startPlaybackTimer Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: [1788079883897] ControllerSpotify::clearAddPlayTrack Aug 30 10:51:23 lazzaroaudio-mediaplayer volumio[1216]: info: Sending Spotify command with payload to local API: /player/play Aug 30 10:51:24 lazzaroaudio-mediaplayer volumio[1216]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:25 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 30 10:51:25 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:25 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2562]: go-librespot daemon starting... Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=debug msg="app state loaded" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=info msg="zeroconf server listening on port 46485" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=debug msg="obtained new client token: AAHzg4MQNd8WnaXxe75SjUZ+HYmnRU64Hap3S+xj9mOQvQ8qNxw+Cw6rBqt5JUhUE00KyPNaomw77/4tcUEFpY+zm67yN83tkqruJb6iDfLFooSZxAScqPJD+JQWXAyKK+FiRz7LjBgrzaCqNlyfvQ4VbmkhyXDQra1K5eBlW9nODikdyNboRL4de3Bn5lSEXWkAjpvD/WfRgjOsVrwGIpAYVilC1bHW1tUHAMCOFr6kHq9JGwHVt5iy" Aug 30 10:51:25 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:26 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:26+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:26 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:26+02:00" level=debug msg="completed challenge" Aug 30 10:51:26 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:26+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:26 lazzaroaudio-mediaplayer go-librespot[2563]: time="2026-08-30T10:51:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:26 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:26 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:26 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:26 lazzaroaudio-mediaplayer volumio[1216]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 10:51:28 lazzaroaudio-mediaplayer volumio[1216]: error: Discovery: Generic acvertisement error: TypeError: argument 8 must be an integer (port) Aug 30 10:51:28 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Stopping Advertising Immediately Aug 30 10:51:28 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Restarting Advertising Aug 30 10:51:29 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: WNM: Preferred List Available Aug 30 10:51:29 lazzaroaudio-mediaplayer wpa_supplicant[1176]: wlan0: Trying to associate with 6c:63:f8:53:78:e7 (SSID='Vodafone-Flea' freq=5200 MHz) Aug 30 10:51:29 lazzaroaudio-mediaplayer wpa_supplicant[1176]: FT: Invalid key management type (2) Aug 30 10:51:29 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: carrier lost - roaming Aug 30 10:51:29 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: changing route to 192.168.20.0/24 Aug 30 10:51:29 lazzaroaudio-mediaplayer dhcpcd[789]: wlan0: changing default route via 192.168.20.1 Aug 30 10:51:29 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 30 10:51:29 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:29 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:29 lazzaroaudio-mediaplayer go-librespot[2596]: go-librespot daemon starting... Aug 30 10:51:29 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:29+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:29 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:29+02:00" level=debug msg="app state loaded" Aug 30 10:51:29 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: Initializing connection to go-librespot Websocket Aug 30 10:51:29 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:29+02:00" level=debug msg="new websocket client" Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: Connection to go-librespot Websocket established Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:51:29.714+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2ec6a80" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address=192.168.20.223/24 ip6Address= ssid=Vodafone-Flea Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: Received Get System Info Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Getting this device information Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:51:29 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:51:29.780+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f7b7d0" available=true connected=true macAddress=e4:5f:01:79:83:79 ip4Address=192.168.20.223/24 ip6Address= ssid=Vodafone-Flea Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: IO: Deleting interface #5 wlan0, 192.168.20.223#123, interface stats: received=3, sent=5, dropped=0, active_time=30 secs Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 195.32.70.195 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 162.159.200.1 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 172.232.208.229 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 93.94.88.50 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 204.216.214.76 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 185.157.229.254 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 95.110.254.234 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 37.247.53.178 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 185.19.184.35 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 89.46.74.148 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 162.159.200.123 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 172.232.209.103 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 93.57.121.105 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 217.61.62.224 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 212.45.144.3 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer ntpd[1024]: PROTO: 44.30.181.131 unlink local addr 192.168.20.223 -> Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::ClearQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::clearPlayQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio5-onboarding[1503]: time=2026-08-30T10:51:30.650+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPlay Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index 0 Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index undefined Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::startPlaybackTimer Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: [1788079890847] ControllerSpotify::clearAddPlayTrack Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Sending Spotify command with payload to local API: /player/play Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::ClearQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::clearPlayQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:30 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPlay Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index 0 Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:7D64ejn2d3g6QaahkYdKXH Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:2DW0Mowto3hrXkFBQt0nye Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index undefined Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::startPlaybackTimer Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: [1788079891114] ControllerSpotify::clearAddPlayTrack Aug 30 10:51:31 lazzaroaudio-mediaplayer volumio[1216]: info: Sending Spotify command with payload to local API: /player/play Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: Getting Spotify volume Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioGetState Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::ClearQueue Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::clearPlayQueue Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::addQueueItems Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::addQueueItems Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: Preload queue cleared Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: Adding Item to queue: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: Using cached record of: spotify:track:1PtQJZVZIdWIYdARpZRDFO Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::saveQueue Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::updateTrackBlock Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrackBlock Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreCommandRouter::volumioPlay Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index 0 Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::stop Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::play index undefined Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CoreStateMachine::startPlaybackTimer Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: CorePlayQueue::getTrack 0 Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: [1788079892960] ControllerSpotify::clearAddPlayTrack Aug 30 10:51:32 lazzaroaudio-mediaplayer volumio[1216]: info: Sending Spotify command with payload to local API: /player/play Aug 30 10:51:33 lazzaroaudio-mediaplayer volumio[1216]: info: Discovery: Started advertising with name: LazzaroAudio MediaPlayer Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=info msg="zeroconf server listening on port 34041" Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=debug msg="obtained new client token: AAHdC8uXjuL3V/i7elZ2+fbqLgYMYAp0jQXm+DAv+RKF2RGt0WhUJ1kOjjaFHdyEJHrjCzjnHrOi7NWsFbRInafJdf7+5tkzk/8KIk2f7cNgcwDXqZDxTWDvOe4gEJGTLUG4YZ8aSw1+wfZ+5yM4gWPixUdJH/cyXCOgDp9CoPyvOc0rr+QizmLCKHBWiJwM1xVWWGgXruiaPEtNkvEwS0Sn7X4wGb63O3rxY0TscB4GipC+drqtNcrv" Aug 30 10:51:34 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:35 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:35+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:35 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:35+02:00" level=debug msg="completed challenge" Aug 30 10:51:35 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:35+02:00" level=info msg="authenticated AP" username="11*******07" Aug 30 10:51:35 lazzaroaudio-mediaplayer go-librespot[2597]: time="2026-08-30T10:51:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 10:51:35 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:35 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: Error: socket hang up Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: at connResetException (node:internal/errors:720:14) Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: at Socket.socketOnEnd (node:_http_client:519:23) Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: at Socket.emit (node:events:526:35) Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: at endReadableNT (node:internal/streams/readable:1376:12) Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: code: 'ECONNRESET', Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: response: undefined Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: } Aug 30 10:51:35 lazzaroaudio-mediaplayer volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:51:37 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 13. Aug 30 10:51:37 lazzaroaudio-mediaplayer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:51:37 lazzaroaudio-mediaplayer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 10:51:37 lazzaroaudio-mediaplayer upmpdcli[2632]: Could not open config: /tmp/upmpdcli.conf Aug 30 10:51:37 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 30 10:51:37 lazzaroaudio-mediaplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 30 10:51:38 lazzaroaudio-mediaplayer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 30 10:51:38 lazzaroaudio-mediaplayer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:38 lazzaroaudio-mediaplayer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2633]: go-librespot daemon starting... Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=debug msg="app state loaded" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=info msg="zeroconf server listening on port 35079" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 10:51:38 lazzaroaudio-mediaplayer sudo[2645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 10:50' Aug 30 10:51:38 lazzaroaudio-mediaplayer sudo[2645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=debug msg="obtained new client token: AAHLH1o6vYSWkrGwac4nRfGYpiNxYI6O3I7JqdzgNtMkJbWG9yXCd8IbOo/HYoKC3Fe4TxxAfcdsMb7w98mubDqDAOV4E+65pOP8/tF+9rtEQPXFIbdzy18AHiH9L6F0gdxVQDQX/SpSAVpN8wCSMr86RLoe6miAd2wo1XIKGgmVZEsmtXb4ALRlqLD9lmjcFC0vrh6yupedx+katjlk9KIUidoKrzC68a1CNEytBjZnD+dEI5bAeB1P" Aug 30 10:51:38 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 10:51:39 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:39+02:00" level=debug msg="completed keyexchange" Aug 30 10:51:39 lazzaroaudio-mediaplayer go-librespot[2634]: time="2026-08-30T10:51:39+02:00" level=debug msg="completed challenge" 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"