Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: Discovery: adding 9d15e54e-f811-482c-8e73-7a3cccc9d314
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: Discovery: Found device Volumio - SP Sala
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetState
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: CorePlayQueue::getTrack 0
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: Discovery: this is already registered, 9d15e54e-f811-482c-8e73-7a3cccc9d314
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: Discovery: Found device Volumio - SP Sala
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetState
Dec 06 11:41:00 volumio---sp-sala volumio[2112]: info: CorePlayQueue::getTrack 0
Dec 06 11:41:01 volumio---sp-sala kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Dec 06 11:41:01 volumio---sp-sala kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Dec 06 11:41:01 volumio---sp-sala sudo[2359]: pam_unix(sudo:session): session closed for user root
Dec 06 11:41:02 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Dec 06 11:41:02 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:02 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:02 volumio---sp-sala go-librespot[2447]: go-librespot daemon starting...
Dec 06 11:41:02 volumio---sp-sala go-librespot[2448]: time="2025-12-06T11:41:02-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:41:02 volumio---sp-sala go-librespot[2448]: time="2025-12-06T11:41:02-03:00" level=debug msg="app state loaded"
Dec 06 11:41:02 volumio---sp-sala go-librespot[2448]: time="2025-12-06T11:41:02-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: info: Cannot mount NAS NAS_Mirror at system boot, trial number 2 ,retrying in 5 seconds
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=70732f6612aeaac3ad556c046a5b1bb8" http://updates.volumio.org/downloader-v1/track-device
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: Dload Upload Total Spent Left Speed
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: [685B blob data]
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: retrying in 5 seconds, trial 0
Dec 06 11:41:07 volumio---sp-sala volumio[2112]: info: Volumio Calling Home
Dec 06 11:41:09 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Dec 06 11:41:10 volumio---sp-sala volumio[2112]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: error: MyVolumio Plugin failed to start in a timely fashion
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: [Metrics] CommandRouter: 40s 252.52ms
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::Close All Modals sent
Dec 06 11:41:12 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::Close All Modals sent
Dec 06 11:41:12 volumio---sp-sala go-librespot[2448]: time="2025-12-06T11:41:12-03: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"
Dec 06 11:41:12 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:41:12 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:41:13 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 06 11:41:13 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 06 11:41:13 volumio---sp-sala sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pedro,password=saveferris,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 '//192.168.15.11/NAS_Mirror/Arquivos Pessoais/Music' /mnt/NAS/NAS_Mirror
Dec 06 11:41:13 volumio---sp-sala sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:41:13 volumio---sp-sala kernel: CIFS: Attempting to mount //192.168.15.11/NAS_Mirror/Arquivos Pessoais/Music
Dec 06 11:41:13 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop
Dec 06 11:41:13 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 06 11:41:15 volumio---sp-sala dhcpcd[838]: eth0: offered 192.168.15.17 from 192.168.15.1
Dec 06 11:41:15 volumio---sp-sala dhcpcd[838]: eth0: probing address 192.168.15.17/24
Dec 06 11:41:16 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Dec 06 11:41:16 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:16 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:16 volumio---sp-sala go-librespot[2499]: go-librespot daemon starting...
Dec 06 11:41:16 volumio---sp-sala go-librespot[2500]: time="2025-12-06T11:41:16-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:41:16 volumio---sp-sala go-librespot[2500]: time="2025-12-06T11:41:16-03:00" level=debug msg="app state loaded"
Dec 06 11:41:16 volumio---sp-sala go-librespot[2500]: time="2025-12-06T11:41:16-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:41:20 volumio---sp-sala dhcpcd[838]: eth0: leased 192.168.15.17 for 60 seconds
Dec 06 11:41:20 volumio---sp-sala avahi-daemon[688]: Registering new address record for 192.168.15.17 on eth0.IPv4.
Dec 06 11:41:20 volumio---sp-sala dhcpcd[648]: eth0: pid 838 deleted default route
Dec 06 11:41:20 volumio---sp-sala dhcpcd[838]: eth0: adding route to 192.168.15.0/24
Dec 06 11:41:20 volumio---sp-sala dhcpcd[838]: eth0: changing default route via 192.168.15.1
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:41:20 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:41:20 volumio---sp-sala avahi-daemon[688]: Withdrawing address record for 169.254.19.4 on eth0.
Dec 06 11:41:20 volumio---sp-sala dhcpcd[648]: eth0: pid 838 deleted IP address 169.254.19.4/16
Dec 06 11:41:20 volumio---sp-sala avahi-daemon[688]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.19.4.
Dec 06 11:41:20 volumio---sp-sala avahi-daemon[688]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.15.17.
Dec 06 11:41:20 volumio---sp-sala dhcpcd[648]: eth0: deleting route to 169.254.0.0/16
Dec 06 11:41:20 volumio---sp-sala dhcpcd[838]: eth0: deleting route to 169.254.0.0/16
Dec 06 11:41:20 volumio---sp-sala welcome[2516]: Resolved ip:[1] 192.168.15.17
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:20 volumio---sp-sala dhcpcd[648]: eth0: probing for an IPv4LL address
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:41:20 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:41:20 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:41:21 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:41:21 volumio---sp-sala welcome[2560]: Resolved ip:[1] 192.168.15.17
Dec 06 11:41:21 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:41:21 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:22 volumio---sp-sala ntpd[1034]: IO: Listen normally on 4 eth0 192.168.15.17:123
Dec 06 11:41:22 volumio---sp-sala ntpd[1034]: IO: Deleting interface #3 eth0, 169.254.19.4#123, interface stats: received=0, sent=0, dropped=0, active_time=121 secs
Dec 06 11:41:22 volumio---sp-sala ntpd[1034]: IO: new interface(s) found: waking up resolver
Dec 06 11:41:22 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:41:23 volumio---sp-sala kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Dec 06 11:41:23 volumio---sp-sala kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Dec 06 11:41:23 volumio---sp-sala sudo[2477]: pam_unix(sudo:session): session closed for user root
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for C1
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: aplay: main:831: audio open error: No such device
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 06 11:41:24 volumio---sp-sala volumio[2112]: info: BOOT COMPLETED
Dec 06 11:41:25 volumio---sp-sala dhcpcd[648]: eth0: using IPv4LL address 169.254.48.191
Dec 06 11:41:25 volumio---sp-sala avahi-daemon[688]: Registering new address record for 169.254.48.191 on eth0.IPv4.
Dec 06 11:41:25 volumio---sp-sala dhcpcd[648]: eth0: adding route to 169.254.0.0/16
Dec 06 11:41:25 volumio---sp-sala dhcpcd[648]: eth0: adding default route
Dec 06 11:41:25 volumio---sp-sala dhcpcd[838]: eth0: pid 648 deleted default route via 192.168.15.1
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:41:25 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:41:25 volumio---sp-sala welcome[2584]: Resolved ip:[2] 192.168.15.17 169.254.48.191
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:41:25 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:25 volumio---sp-sala volumio[2112]: info: Cannot mount NAS NAS_Mirror at system boot, trial number 3 ,retrying in 5 seconds
Dec 06 11:41:27 volumio---sp-sala ntpd[1034]: IO: Listen normally on 5 eth0 169.254.48.191:123
Dec 06 11:41:27 volumio---sp-sala ntpd[1034]: IO: new interface(s) found: waking up resolver
Dec 06 11:41:29 volumio---sp-sala go-librespot[2500]: time="2025-12-06T11:41:29-03: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"
Dec 06 11:41:29 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:41:29 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:41:32 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Dec 06 11:41:32 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:32 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:32 volumio---sp-sala go-librespot[2604]: go-librespot daemon starting...
Dec 06 11:41:32 volumio---sp-sala go-librespot[2605]: time="2025-12-06T11:41:32-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:41:32 volumio---sp-sala go-librespot[2605]: time="2025-12-06T11:41:32-03:00" level=debug msg="app state loaded"
Dec 06 11:41:32 volumio---sp-sala go-librespot[2605]: time="2025-12-06T11:41:32-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: info: Error connecting to go-librespot Websocket: Error: socket hang up
Dec 06 11:41:40 volumio---sp-sala sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pedro,password=saveferris,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 '//192.168.15.11/NAS_Mirror/Arquivos Pessoais/Music' /mnt/NAS/NAS_Mirror
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=70732f6612aeaac3ad556c046a5b1bb8" http://updates.volumio.org/downloader-v1/track-device
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: Dload Upload Total Spent Left Speed
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: [843B blob data]
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: retrying in 5 seconds, trial 1
Dec 06 11:41:40 volumio---sp-sala sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:41:40 volumio---sp-sala volumio[2112]: info: Volumio Calling Home
Dec 06 11:41:40 volumio---sp-sala kernel: CIFS: Attempting to mount //192.168.15.11/NAS_Mirror/Arquivos Pessoais/Music
Dec 06 11:41:41 volumio---sp-sala sudo[2629]: pam_unix(sudo:session): session closed for user root
Dec 06 11:41:43 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Dec 06 11:41:43 volumio---sp-sala ntpd[1034]: DNS: dns_check: DNS error: -11, System error
Dec 06 11:41:43 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12
Dec 06 11:41:43 volumio---sp-sala volumio[2112]: info: Initializing connection to go-librespot Websocket
Dec 06 11:41:44 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:41:48 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Dec 06 11:41:50 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Dec 06 11:41:50 volumio---sp-sala ntpd[1034]: DNS: dns_check: DNS error: -11, System error
Dec 06 11:41:50 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12
Dec 06 11:41:50 volumio---sp-sala go-librespot[2605]: time="2025-12-06T11:41:50-03: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"
Dec 06 11:41:50 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:41:50 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:41:50 volumio---sp-sala dhcpcd[838]: eth0: adding default route via 192.168.15.1
Dec 06 11:41:50 volumio---sp-sala dhcpcd[648]: eth0: pid 838 deleted default route
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:41:50 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:41:50 volumio---sp-sala welcome[2682]: Resolved ip:[2] 192.168.15.17 169.254.48.191
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:41:50 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:41:51 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:41:53 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Dec 06 11:41:53 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:53 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:41:53 volumio---sp-sala go-librespot[2700]: go-librespot daemon starting...
Dec 06 11:41:53 volumio---sp-sala go-librespot[2701]: time="2025-12-06T11:41:53-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:41:53 volumio---sp-sala go-librespot[2701]: time="2025-12-06T11:41:53-03:00" level=debug msg="app state loaded"
Dec 06 11:41:53 volumio---sp-sala go-librespot[2701]: time="2025-12-06T11:41:53-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:42:07 volumio---sp-sala volumio[2112]: Cannot compose Albumart path
Dec 06 11:42:08 volumio---sp-sala volumio[2112]: Cannot compose Albumart path
Dec 06 11:42:13 volumio---sp-sala go-librespot[2701]: time="2025-12-06T11:42:13-03: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T11:42:12-03:00 is before 2025-12-08T00:00:00Z"
Dec 06 11:42:13 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:42:13 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:42:14 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Dec 06 11:42:14 volumio---sp-sala ntpd[1034]: DNS: dns_check: DNS error: -11, System error
Dec 06 11:42:14 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: Received Get System Info
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: Discovery: Getting this device information
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetState
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: CorePlayQueue::getTrack 0
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 06 11:42:14 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 192.36.143.130
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 200.160.7.197
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 216.238.112.29
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 168.181.126.28
Dec 06 11:42:15 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin multiroom to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 06 11:42:15 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 06 11:42:16 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Dec 06 11:42:16 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:16 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:16 volumio---sp-sala go-librespot[2760]: go-librespot daemon starting...
Dec 06 11:42:16 volumio---sp-sala go-librespot[2761]: time="2025-12-06T11:42:16-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:42:16 volumio---sp-sala go-librespot[2761]: time="2025-12-06T11:42:16-03:00" level=debug msg="app state loaded"
Dec 06 11:42:16 volumio---sp-sala go-librespot[2761]: time="2025-12-06T11:42:16-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:42:16 volumio---sp-sala go-librespot[2761]: time="2025-12-06T11:42:16-03: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T11:42:16-03:00 is before 2025-12-08T00:00:00Z"
Dec 06 11:42:16 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:42:16 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:42:19 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 06 11:42:19 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:19 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:19 volumio---sp-sala go-librespot[2783]: go-librespot daemon starting...
Dec 06 11:42:19 volumio---sp-sala go-librespot[2784]: time="2025-12-06T11:42:19-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:42:19 volumio---sp-sala go-librespot[2784]: time="2025-12-06T11:42:19-03:00" level=debug msg="app state loaded"
Dec 06 11:42:19 volumio---sp-sala go-librespot[2784]: time="2025-12-06T11:42:19-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:42:19 volumio---sp-sala dhcpcd[648]: eth0: offered 192.168.15.16 from 192.168.15.1
Dec 06 11:42:19 volumio---sp-sala dhcpcd[648]: eth0: NAK: from 192.168.15.1
Dec 06 11:42:20 volumio---sp-sala dhcpcd[648]: eth0: soliciting a DHCP lease
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: MyVolumio login type: Token
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 06 11:42:20 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 06 11:42:20 volumio---sp-sala dhcpcd[838]: eth0: NAK: from 192.168.15.1
Dec 06 11:42:20 volumio---sp-sala avahi-daemon[688]: Withdrawing address record for 192.168.15.17 on eth0.
Dec 06 11:42:20 volumio---sp-sala dhcpcd[838]: eth0: deleting route to 192.168.15.0/24
Dec 06 11:42:20 volumio---sp-sala avahi-daemon[688]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.15.17.
Dec 06 11:42:20 volumio---sp-sala avahi-daemon[688]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.48.191.
Dec 06 11:42:20 volumio---sp-sala dhcpcd[838]: eth0: deleting default route via 192.168.15.1
Dec 06 11:42:20 volumio---sp-sala dhcpcd[648]: eth0: adding default route
Dec 06 11:42:20 volumio---sp-sala dhcpcd[648]: eth0: pid 0 deleted default route
Dec 06 11:42:20 volumio---sp-sala dhcpcd[838]: eth0: soliciting a DHCP lease
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:42:20 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:42:20 volumio---sp-sala welcome[2810]: Resolved ip:[1] 169.254.48.191
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:42:20 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:21 volumio---sp-sala dhcpcd[838]: eth0: offered 192.168.15.19 from 192.168.15.1
Dec 06 11:42:21 volumio---sp-sala dhcpcd[838]: eth0: probing address 192.168.15.19/24
Dec 06 11:42:22 volumio---sp-sala ntpd[1034]: IO: Deleting interface #4 eth0, 192.168.15.17#123, interface stats: received=3, sent=6, dropped=1, active_time=60 secs
Dec 06 11:42:22 volumio---sp-sala ntpd[1034]: PROTO: 168.181.126.28 unlink local addr 192.168.15.17 ->
Dec 06 11:42:22 volumio---sp-sala ntpd[1034]: PROTO: 216.238.112.29 unlink local addr 192.168.15.17 ->
Dec 06 11:42:22 volumio---sp-sala ntpd[1034]: PROTO: 200.160.7.197 unlink local addr 192.168.15.17 ->
Dec 06 11:42:22 volumio---sp-sala ntpd[1034]: PROTO: 192.36.143.130 unlink local addr 192.168.15.17 ->
Dec 06 11:42:23 volumio---sp-sala dhcpcd[648]: eth0: offered 192.168.15.19 from 192.168.15.1
Dec 06 11:42:23 volumio---sp-sala dhcpcd[648]: eth0: probing address 192.168.15.19/24
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: Streaming services startup
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: Starting Streaming Daemon
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 06 11:42:25 volumio---sp-sala sudo[2818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 06 11:42:25 volumio---sp-sala sudo[2818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:25 volumio---sp-sala sudo[2818]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: Initializing connection to go-librespot Websocket
Dec 06 11:42:25 volumio---sp-sala dhcpcd[838]: eth0: leased 192.168.15.19 for 7200 seconds
Dec 06 11:42:25 volumio---sp-sala avahi-daemon[688]: Registering new address record for 192.168.15.19 on eth0.IPv4.
Dec 06 11:42:25 volumio---sp-sala dhcpcd[838]: eth0: adding route to 192.168.15.0/24
Dec 06 11:42:25 volumio---sp-sala dhcpcd[838]: eth0: adding default route via 192.168.15.1
Dec 06 11:42:25 volumio---sp-sala dhcpcd[648]: eth0: adding default route
Dec 06 11:42:25 volumio---sp-sala dhcpcd[648]: eth0: pid 838 deleted default route
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=70732f6612aeaac3ad556c046a5b1bb8" http://updates.volumio.org/downloader-v1/track-device
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: Dload Upload Total Spent Left Speed
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: [1.2K blob data]
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: retrying in 5 seconds, trial 2
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: Volumio Calling Home
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:42:25 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Dec 06 11:42:25 volumio---sp-sala welcome[2843]: Resolved ip:[2] 169.254.48.191 192.168.15.19
Dec 06 11:42:25 volumio---sp-sala volumio[2112]: info: MyVolumio login type: Token
Dec 06 11:42:25 volumio---sp-sala avahi-daemon[688]: Withdrawing address record for 169.254.48.191 on eth0.
Dec 06 11:42:25 volumio---sp-sala avahi-daemon[688]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.48.191.
Dec 06 11:42:25 volumio---sp-sala avahi-daemon[688]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.15.19.
Dec 06 11:42:25 volumio---sp-sala dhcpcd[648]: eth0: pid 838 deleted IP address 169.254.48.191/16
Dec 06 11:42:25 volumio---sp-sala dhcpcd[648]: eth0: deleting route to 169.254.0.0/16
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:25 volumio---sp-sala dhcpcd[648]: eth0: probing for an IPv4LL address
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:42:25 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:42:25 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:42:26 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:42:26 volumio---sp-sala welcome[2885]: Resolved ip:[1] 192.168.15.19
Dec 06 11:42:26 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:42:26 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:26 volumio---sp-sala volumio[2112]: error: Cannot start Volumio Streaming Daemon
Dec 06 11:42:26 volumio---sp-sala volumio[2112]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 06 11:42:26 volumio---sp-sala volumio[2112]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 06 11:42:26 volumio---sp-sala nmbd[1106]: [2025/12/06 11:42:26.237304, 0] ../../source3/libsmb/nmblib.c:923(send_udp)
Dec 06 11:42:26 volumio---sp-sala nmbd[1106]: Packet send failed to 192.168.15.255(138) ERRNO=Network is unreachable
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: IO: Listen normally on 6 eth0 192.168.15.19:123
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: IO: Deleting interface #5 eth0, 169.254.48.191#123, interface stats: received=0, sent=2, dropped=0, active_time=60 secs
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: PROTO: 192.36.143.130 unlink local addr 169.254.48.191 ->
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: PROTO: 200.160.7.197 unlink local addr 169.254.48.191 ->
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: PROTO: 216.238.112.29 unlink local addr 169.254.48.191 ->
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: PROTO: 168.181.126.28 unlink local addr 169.254.48.191 ->
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: IO: new interface(s) found: waking up resolver
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 200.160.7.186
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 200.160.7.193
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: Pool skipping: 216.238.112.29
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 162.159.200.123
Dec 06 11:42:27 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8
Dec 06 11:42:28 volumio---sp-sala dhcpcd[648]: eth0: leased 192.168.15.19 for 7200 seconds
Dec 06 11:42:28 volumio---sp-sala dhcpcd[648]: eth0: adding route to 192.168.15.0/24
Dec 06 11:42:28 volumio---sp-sala dhcpcd[648]: eth0: adding default route via 192.168.15.1
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Dec 06 11:42:28 volumio---sp-sala systemd[1]: welcome.service: Deactivated successfully.
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Stopped welcome.service - Show a welcome message on console.
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Stopping welcome.service - Show a welcome message on console...
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Starting welcome.service - Show a welcome message on console...
Dec 06 11:42:28 volumio---sp-sala welcome[2923]: Resolved ip:[1] 192.168.15.19
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Finished welcome.service - Show a welcome message on console.
Dec 06 11:42:28 volumio---sp-sala systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 200.160.7.196
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 200.192.232.8
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 143.107.229.211
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 172.233.29.160
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 2001:19f0:b800:1f31:5400:4ff:fe5a:d8a2
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 2600:3c0d::2000:dcff:fe50:5ea8
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 2605:e440:24::1:1b1
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 2001:19f0:b800:1c73:5400:5ff:fe04:48c3
Dec 06 11:42:28 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: Pool skipping: 192.36.143.130
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 194.58.38.95
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 200.160.0.8
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: Pool taking: 143.107.229.210
Dec 06 11:42:29 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: Pool skipping: 192.36.143.130
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: Pool skipping: 200.160.7.197
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: Pool skipping: 216.238.112.29
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: Pool skipping: 168.181.126.28
Dec 06 11:42:30 volumio---sp-sala ntpd[1034]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Dec 06 11:42:35 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: Retrying Login Due to Network Error
Dec 06 11:42:40 volumio---sp-sala go-librespot[2784]: time="2025-12-06T11:42:40-03:00" level=debug msg="new websocket client"
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: info: Connection to go-librespot Websocket established
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Dec 06 11:42:40 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Dec 06 11:42:41 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetState
Dec 06 11:42:41 volumio---sp-sala volumio[2112]: info: CorePlayQueue::getTrack 0
Dec 06 11:42:41 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetState
Dec 06 11:42:41 volumio---sp-sala volumio[2112]: info: CorePlayQueue::getTrack 0
Dec 06 11:42:42 volumio---sp-sala volumio[2112]: (node:2112) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit
Dec 06 11:42:42 volumio---sp-sala volumio[2112]: (Use `node --trace-warnings ...` to show where the warning was created)
Dec 06 11:42:43 volumio---sp-sala volumio[2112]: info: Getting Spotify volume
Dec 06 11:42:43 volumio---sp-sala volumio[2112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Dec 06 11:42:44 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::volumioGetState
Dec 06 11:42:44 volumio---sp-sala volumio[2112]: info: CorePlayQueue::getTrack 0
Dec 06 11:42:47 volumio---sp-sala volumio[2112]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 06 11:42:48 volumio---sp-sala volumio[2112]: info: MyVolumio token set successfully
Dec 06 11:42:48 volumio---sp-sala volumio[2112]: info: MYVOLUMIO: Adding device
Dec 06 11:42:48 volumio---sp-sala volumio[2112]: info: MYVOLUMIO: Evaluating Server
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: MyVolumio Plan changed: premium
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Removing browser output: myVolumio user plan is not superstar
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Removing audio output:
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: MYVOLUMIO: Adding device
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: MYVOLUMIO: Evaluating Server
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Remote config written successfully
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Starting Tunnel 1
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Starting Tunnel Connection Checker
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: MYVolumio Device enabled
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: MyVolumio status changed
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Streaming services startup
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Starting Streaming Daemon
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: Setting Geolocation for MyVolumio to us1
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Dec 06 11:42:49 volumio---sp-sala sudo[3009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 06 11:42:49 volumio---sp-sala sudo[3009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:49 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Dec 06 11:42:49 volumio---sp-sala sudo[3009]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:49 volumio---sp-sala go-librespot[2784]: time="2025-12-06T11:42:49-03: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\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)"
Dec 06 11:42:49 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:42:49 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:42:50 volumio---sp-sala volumio[2112]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Dec 06 11:42:50 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Dec 06 11:42:53 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 06 11:42:53 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:53 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:53 volumio---sp-sala go-librespot[3014]: go-librespot daemon starting...
Dec 06 11:42:53 volumio---sp-sala go-librespot[3015]: time="2025-12-06T11:42:53-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:42:53 volumio---sp-sala go-librespot[3015]: time="2025-12-06T11:42:53-03:00" level=debug msg="app state loaded"
Dec 06 11:42:53 volumio---sp-sala go-librespot[3015]: time="2025-12-06T11:42:53-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:42:53 volumio---sp-sala go-librespot[3015]: time="2025-12-06T11:42:53-03: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T11:42:53-03:00 is before 2025-12-08T00:00:00Z"
Dec 06 11:42:53 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:42:53 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:42:54 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 06 11:42:54 volumio---sp-sala sudo[3024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 06 11:42:54 volumio---sp-sala sudo[3024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:54 volumio---sp-sala sudo[3024]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:54 volumio---sp-sala volumio[2112]: info: MRS: MultiRoom plugin initialized
Dec 06 11:42:54 volumio---sp-sala volumio[2112]: info: MRS: STOPPING SNAPCLIENT
Dec 06 11:42:54 volumio---sp-sala volumio[2112]: info: MRS: Snap server stop
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: MRS: STOPPING volumioStreaming
Dec 06 11:42:55 volumio---sp-sala sudo[3041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 06 11:42:55 volumio---sp-sala sudo[3041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 06 11:42:55 volumio---sp-sala sudo[3044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Dec 06 11:42:55 volumio---sp-sala sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:55 volumio---sp-sala sudo[3048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Dec 06 11:42:55 volumio---sp-sala sudo[3048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:55 volumio---sp-sala sudo[3041]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:55 volumio---sp-sala sudo[3045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Dec 06 11:42:55 volumio---sp-sala sudo[3045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:42:55 volumio---sp-sala sudo[3048]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:55 volumio---sp-sala sudo[3044]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Dec 06 11:42:55 volumio---sp-sala sudo[3045]: pam_unix(sudo:session): session closed for user root
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 06 11:42:55 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: Preparing to generate the ALSA configuration file
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: MyVolumio login type: Token
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: Updating MyVolumio device info
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: Error: socket hang up
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: at connResetException (node:internal/errors:720:14)
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: at Socket.socketOnEnd (node:_http_client:519:23)
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: at Socket.emit (node:events:526:35)
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: at endReadableNT (node:internal/streams/readable:1376:12)
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: code: 'ECONNRESET',
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: response: undefined
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: }
Dec 06 11:42:56 volumio---sp-sala volumio[2112]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 06 11:42:56 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 06 11:42:56 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:56 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:42:56 volumio---sp-sala go-librespot[3055]: go-librespot daemon starting...
Dec 06 11:42:56 volumio---sp-sala go-librespot[3059]: time="2025-12-06T11:42:56-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:42:56 volumio---sp-sala go-librespot[3059]: time="2025-12-06T11:42:56-03:00" level=debug msg="app state loaded"
Dec 06 11:42:56 volumio---sp-sala go-librespot[3059]: time="2025-12-06T11:42:56-03:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 06 11:42:57 volumio---sp-sala go-librespot[3059]: time="2025-12-06T11:42:57-03: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T11:42:56-03:00 is before 2025-12-08T00:00:00Z"
Dec 06 11:42:57 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 06 11:42:57 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 06 11:42:59 volumio---sp-sala sudo[3090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 11:41'
Dec 06 11:42:59 volumio---sp-sala sudo[3090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 06 11:43:00 volumio---sp-sala systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 06 11:43:00 volumio---sp-sala systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:43:00 volumio---sp-sala systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 06 11:43:00 volumio---sp-sala go-librespot[3092]: go-librespot daemon starting...
Dec 06 11:43:00 volumio---sp-sala go-librespot[3093]: time="2025-12-06T11:43:00-03:00" level=info msg="running go-librespot 0.4.0"
Dec 06 11:43:00 volumio---sp-sala go-librespot[3093]: time="2025-12-06T11:43:00-03:00" level=debug msg="app state loaded"
Dec 06 11:43:00 volumio---sp-sala go-librespot[3093]: time="2025-12-06T11:43:00-03:00" level=info msg="api server listening on 127.0.0.1:9879"
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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025"
VOLUMIO_VERSION="4.071"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"