Dec 05 21:47:00 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 05 21:47:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Dec 05 21:47:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:01 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:01 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: AggregateError Dec 05 21:47:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:01 volumio go-librespot[2874]: go-librespot daemon starting... Dec 05 21:47:01 volumio go-librespot[2875]: time="2025-12-05T21:47:01+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:01 volumio go-librespot[2875]: time="2025-12-05T21:47:01+01:00" level=debug msg="app state loaded" Dec 05 21:47:01 volumio go-librespot[2875]: time="2025-12-05T21:47:01+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:01 volumio go-librespot[2875]: time="2025-12-05T21:47:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:01 volumio go-librespot[2875]: time="2025-12-05T21:47:01+01:00" level=fatal msg="failed running zeroconf" 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 05 21:47:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:03 volumio volumio[1043]: info: MyVolumio login type: Token Dec 05 21:47:03 volumio volumio[1043]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 05 21:47:03 volumio volumio[1043]: info: Retrying Login Due to Network Error Dec 05 21:47:03 volumio dhcpcd[733]: eth0: leased 192.168.1.227 for 259200 seconds Dec 05 21:47:03 volumio avahi-daemon[638]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.227. Dec 05 21:47:03 volumio avahi-daemon[638]: New relevant interface eth0.IPv4 for mDNS. Dec 05 21:47:03 volumio avahi-daemon[638]: Registering new address record for 192.168.1.227 on eth0.IPv4. Dec 05 21:47:03 volumio dhcpcd[733]: eth0: adding route to 192.168.1.0/24 Dec 05 21:47:03 volumio dhcpcd[733]: eth0: adding default route via 192.168.1.1 Dec 05 21:47:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 05 21:47:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 05 21:47:03 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 05 21:47:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 05 21:47:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 05 21:47:04 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:04 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:04 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 05 21:47:04 volumio welcome[2905]: Resolved ip:[1] 192.168.1.227 Dec 05 21:47:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 05 21:47:04 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 05 21:47:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Dec 05 21:47:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:04 volumio go-librespot[2911]: go-librespot daemon starting... Dec 05 21:47:04 volumio go-librespot[2912]: time="2025-12-05T21:47:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:04 volumio go-librespot[2912]: time="2025-12-05T21:47:04+01:00" level=debug msg="app state loaded" Dec 05 21:47:04 volumio go-librespot[2912]: time="2025-12-05T21:47:04+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:04 volumio go-librespot[2912]: time="2025-12-05T21:47:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:04 volumio go-librespot[2912]: time="2025-12-05T21:47:04+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:04+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:04 volumio volumio[1043]: info: Discovery: adding 13997f62-ea01-4067-b879-f050274c70ef Dec 05 21:47:04 volumio volumio[1043]: info: Discovery: Found device Volumio Dec 05 21:47:04 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:04 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:05 volumio sudo[2920]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:05 volumio sudo[2920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:05 volumio sudo[2920]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:05 volumio sudo[2922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:05 volumio sudo[2922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:05 volumio sudo[2922]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:05 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.210 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 05 21:47:05 volumio sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:05 volumio sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:05 volumio sudo[2926]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:05 volumio sudo[2928]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:05 volumio sudo[2928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:05 volumio sudo[2928]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:05 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.210 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 05 21:47:05 volumio ntpd[954]: IO: Listen normally on 4 eth0 192.168.1.227:123 Dec 05 21:47:05 volumio ntpd[954]: IO: new interface(s) found: waking up resolver Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:05 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 05 21:47:05 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:05 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:05 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:05 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:05 volumio volumio[1043]: info: Listing playlists Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 05 21:47:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 21:47:06 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:07 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:07 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 21:47:07 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:07 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:07 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Dec 05 21:47:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:07 volumio go-librespot[2932]: go-librespot daemon starting... Dec 05 21:47:07 volumio go-librespot[2933]: time="2025-12-05T21:47:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:07 volumio go-librespot[2933]: time="2025-12-05T21:47:07+01:00" level=debug msg="app state loaded" Dec 05 21:47:07 volumio go-librespot[2933]: time="2025-12-05T21:47:07+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:07 volumio go-librespot[2933]: time="2025-12-05T21:47:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:08 volumio go-librespot[2933]: time="2025-12-05T21:47:08+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:07+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 21:47:08 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:08 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:08 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 05 21:47:08 volumio volumio[1043]: info: MyVolumio login type: Token Dec 05 21:47:08 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 05 21:47:08 volumio volumio[1043]: info: MyVolumio login type: Token Dec 05 21:47:08 volumio volumio[1043]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 05 21:47:09 volumio volumio[1043]: info: MyVolumio token set successfully Dec 05 21:47:09 volumio volumio[1043]: info: MYVOLUMIO: Adding device Dec 05 21:47:09 volumio volumio[1043]: info: MYVOLUMIO: Evaluating Server Dec 05 21:47:10 volumio volumio[1043]: info: MyVolumio Plan changed: premium Dec 05 21:47:10 volumio volumio[1043]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 05 21:47:10 volumio volumio[1043]: info: Removing browser output: myVolumio user plan is not superstar Dec 05 21:47:10 volumio volumio[1043]: info: Removing audio output: Dec 05 21:47:10 volumio volumio[1043]: info: MYVOLUMIO: Adding device Dec 05 21:47:10 volumio volumio[1043]: info: MYVOLUMIO: Evaluating Server Dec 05 21:47:10 volumio volumio[1043]: info: Remote config written successfully Dec 05 21:47:10 volumio volumio[1043]: info: Starting Tunnel 1 Dec 05 21:47:10 volumio volumio[1043]: info: Starting Tunnel Connection Checker Dec 05 21:47:10 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:10 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:10 volumio volumio[1043]: info: MYVolumio Device disabled Dec 05 21:47:10 volumio volumio[1043]: info: MyVolumio status changed Dec 05 21:47:10 volumio volumio[1043]: info: Streaming services startup Dec 05 21:47:10 volumio volumio[1043]: info: Starting Streaming Daemon Dec 05 21:47:10 volumio volumio[1043]: info: Volumio Network Manager: Network status updated: 1 Dec 05 21:47:10 volumio sudo[2993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 05 21:47:10 volumio sudo[2993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:10 volumio sudo[2993]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:10 volumio volumio[1043]: error: Cannot start Volumio Streaming Daemon Dec 05 21:47:10 volumio volumio[1043]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 05 21:47:10 volumio volumio[1043]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 21:47:10 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:10 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:10 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 21:47:10 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:10 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:10 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:10 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Dec 05 21:47:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:11 volumio go-librespot[2995]: go-librespot daemon starting... Dec 05 21:47:11 volumio go-librespot[2996]: time="2025-12-05T21:47:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:11 volumio go-librespot[2996]: time="2025-12-05T21:47:11+01:00" level=debug msg="app state loaded" Dec 05 21:47:11 volumio go-librespot[2996]: time="2025-12-05T21:47:11+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:11 volumio go-librespot[2996]: time="2025-12-05T21:47:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:11 volumio go-librespot[2996]: time="2025-12-05T21:47:11+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:11+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:11 volumio volumio[1043]: info: Setting Geolocation for MyVolumio to eu7 Dec 05 21:47:11 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:11 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:11 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:12 volumio volumio[1043]: info: MYVolumio Device enabled Dec 05 21:47:12 volumio volumio[1043]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 05 21:47:12 volumio volumio[1043]: info: MyVolumio status changed Dec 05 21:47:12 volumio volumio[1043]: info: Streaming services startup Dec 05 21:47:12 volumio volumio[1043]: info: Starting Streaming Daemon Dec 05 21:47:12 volumio volumio[1043]: info: Successfully Added MyVolumio device Dec 05 21:47:12 volumio volumio[1043]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Dec 05 21:47:12 volumio sudo[3004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 05 21:47:12 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:12 volumio volumio[1043]: info: Setting Geolocation for MyVolumio to eu11 Dec 05 21:47:12 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:12 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:12 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:12 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:12 volumio volumio[1043]: error: Cannot start Volumio Streaming Daemon Dec 05 21:47:12 volumio volumio[1043]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 05 21:47:12 volumio volumio[1043]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 05 21:47:12 volumio volumio[1043]: info: Successfully Added MyVolumio device Dec 05 21:47:12 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 05 21:47:13 volumio volumio[1043]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 05 21:47:13 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 05 21:47:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Dec 05 21:47:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:14 volumio go-librespot[3006]: go-librespot daemon starting... Dec 05 21:47:14 volumio go-librespot[3007]: time="2025-12-05T21:47:14+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:14 volumio go-librespot[3007]: time="2025-12-05T21:47:14+01:00" level=debug msg="app state loaded" Dec 05 21:47:14 volumio go-librespot[3007]: time="2025-12-05T21:47:14+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:14 volumio go-librespot[3007]: time="2025-12-05T21:47:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:15 volumio go-librespot[3007]: time="2025-12-05T21:47:15+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:14+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:16 volumio sudo[3017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 05 21:47:16 volumio sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:16 volumio sudo[3017]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:16 volumio volumio[1043]: info: MRS: MultiRoom plugin initialized Dec 05 21:47:16 volumio volumio[1043]: info: MRS: STOPPING SNAPCLIENT Dec 05 21:47:16 volumio volumio[1043]: info: MRS: Snap server stop Dec 05 21:47:16 volumio volumio[1043]: info: MRS: STOPPING volumioStreaming Dec 05 21:47:16 volumio sudo[3035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 05 21:47:16 volumio sudo[3035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:16 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 05 21:47:16 volumio sudo[3037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 05 21:47:16 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:16 volumio sudo[3039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 05 21:47:16 volumio sudo[3039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:16 volumio sudo[3042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 05 21:47:16 volumio sudo[3042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:16 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 05 21:47:16 volumio sudo[3042]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:16 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 05 21:47:16 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 05 21:47:16 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 05 21:47:17 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 05 21:47:17 volumio sudo[3039]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:17 volumio sudo[3035]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 05 21:47:17 volumio volumio[1043]: info: Preparing to generate the ALSA configuration file Dec 05 21:47:17 volumio volumio[1043]: info: Updating MyVolumio device info Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: Updating MyVolumio device info Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:17 volumio volumio[1043]: info: MyVolumio login type: Token Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 05 21:47:17 volumio volumio[1043]: info: MRS: Removed streaming files Dec 05 21:47:17 volumio volumio[1043]: info: MRS: volumioStreaming STOPPED Dec 05 21:47:17 volumio volumio[1043]: info: MRS: SNAPSERVER STOPPED Dec 05 21:47:17 volumio volumio[1043]: info: MRS: SNAPCLIENT STOPPED Dec 05 21:47:17 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:17 volumio volumio[1043]: info: Asound.conf file unchanged, so no further update is needed Dec 05 21:47:17 volumio volumio[1043]: info: Output device has changed, restarting MPD Dec 05 21:47:17 volumio volumio[1043]: info: Output device has changed, restarting Shairport Sync Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:17 volumio sudo[3049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 21:47:17 volumio sudo[3049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:17 volumio sudo[3049]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:17 volumio sudo[3051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 21:47:17 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:17 volumio volumio[1043]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 05 21:47:17 volumio volumio[1043]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 05 21:47:17 volumio volumio[1043]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 05 21:47:17 volumio volumio[1043]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 05 21:47:17 volumio volumio[1043]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 05 21:47:17 volumio volumio[1043]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 05 21:47:17 volumio volumio[1043]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 05 21:47:17 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 05 21:47:17 volumio volumio[1043]: info: MRS: Pushing multiroomSync output for this device Dec 05 21:47:17 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:17 volumio volumio[1043]: info: Adding audio output: Dec 05 21:47:17 volumio volumio[1043]: info: Adding audio output: Dec 05 21:47:17 volumio volumio[1043]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 05 21:47:17 volumio volumio[1043]: info: Adding METAVOLUMIO REST API Endpoints Dec 05 21:47:17 volumio volumio[1043]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 05 21:47:17 volumio volumio[1043]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 05 21:47:17 volumio volumio[1043]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 05 21:47:17 volumio volumio[1043]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 05 21:47:17 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 05 21:47:17 volumio volumio[1043]: info: Preparing CD Folders Dec 05 21:47:17 volumio volumio[1043]: info: Adding CD REST API Endpoints Dec 05 21:47:17 volumio volumio[1043]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 05 21:47:17 volumio volumio[1043]: info: Starting UDEV Watcher for CD Dec 05 21:47:17 volumio volumio[1043]: info: Detecting CD presence with UDEV Dec 05 21:47:17 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 05 21:47:17 volumio bluetoothd[790]: Failed to set mode: Failed (0x03) Dec 05 21:47:17 volumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.21 Dec 05 21:47:17 volumio bluetoothd[790]: Adv Monitor app :1.21 disconnected from D-Bus Dec 05 21:47:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Dec 05 21:47:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:18 volumio go-librespot[3068]: go-librespot daemon starting... Dec 05 21:47:18 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 21:47:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 21:47:18 volumio systemd[1]: mpd.service: Consumed 5.773s CPU time. Dec 05 21:47:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 21:47:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 21:47:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 21:47:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 21:47:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 21:47:18 volumio go-librespot[3069]: time="2025-12-05T21:47:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:18 volumio go-librespot[3069]: time="2025-12-05T21:47:18+01:00" level=debug msg="app state loaded" Dec 05 21:47:18 volumio go-librespot[3069]: time="2025-12-05T21:47:18+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:18 volumio go-librespot[3069]: time="2025-12-05T21:47:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:18 volumio go-librespot[3069]: time="2025-12-05T21:47:18+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:18+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:18 volumio sudo[3077]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 21:47:18 volumio sudo[3077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 21:47:18 volumio sudo[3077]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:21 volumio kernel: hwmon hwmon1: Voltage normalised Dec 05 21:47:21 volumio mpd[3079]: 2025-12-05T21:47:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 21:47:21 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 21:47:21 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Dec 05 21:47:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:22 volumio go-librespot[3100]: go-librespot daemon starting... Dec 05 21:47:22 volumio go-librespot[3101]: time="2025-12-05T21:47:22+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:22 volumio go-librespot[3101]: time="2025-12-05T21:47:22+01:00" level=debug msg="app state loaded" Dec 05 21:47:22 volumio go-librespot[3101]: time="2025-12-05T21:47:22+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:22 volumio go-librespot[3101]: time="2025-12-05T21:47:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:22 volumio go-librespot[3101]: time="2025-12-05T21:47:22+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:22+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:22 volumio volumio[1043]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 05 21:47:22 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 05 21:47:22 volumio volumio[1043]: info: Adding inputs REST Endpoints Dec 05 21:47:22 volumio volumio[1043]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 05 21:47:22 volumio volumio[1043]: info: Scanning Audio Inputs Dec 05 21:47:22 volumio volumio[1043]: info: Checking against Known Cards name Dec 05 21:47:22 volumio volumio[1043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 05 21:47:22 volumio volumio[1043]: info: [1764967642956] CoreMusicLibrary::Adding element USB Audio CODEC Dec 05 21:47:22 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 21:47:22 volumio volumio[1043]: Cannot find translation for source USB Audio CODEC Dec 05 21:47:22 volumio volumio[1043]: info: Checking against Known Cards name Dec 05 21:47:22 volumio volumio[1043]: info: Adding Server instance for streaming Dec 05 21:47:22 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 05 21:47:22 volumio volumio[1043]: error: Hi Res Audio Failed Login: Missing Login Data Dec 05 21:47:22 volumio volumio[1043]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 05 21:47:22 volumio volumio[1043]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 05 21:47:22 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 05 21:47:22 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 05 21:47:22 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 05 21:47:23 volumio volumio[1043]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 05 21:47:23 volumio volumio[1043]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 05 21:47:23 volumio sudo[3112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 05 21:47:23 volumio sudo[3112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:23 volumio volumio[1043]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 05 21:47:23 volumio sudo[3119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 21:47:23 volumio volumio[1043]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 05 21:47:23 volumio volumio[1043]: info: Adding TIDAL REST API Endpoints Dec 05 21:47:23 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:23 volumio volumio[1043]: info: Stopping AccessToken refresher cron for QOBUZ Dec 05 21:47:23 volumio sudo[3112]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:23 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:23 volumio volumio[1043]: info: AccessToken refresher cron started for QOBUZ Dec 05 21:47:23 volumio volumio[1043]: info: Adding QOBUZ REST API Endpoints Dec 05 21:47:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 05 21:47:23 volumio sudo[3122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 21:47:23 volumio sudo[3122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:23 volumio volumio[1043]: info: MRS: Getting audio outputs on start Dec 05 21:47:23 volumio volumio[1043]: info: MRS: Requesting all other devices output Dec 05 21:47:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 05 21:47:23 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:23 volumio sudo[3122]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:23 volumio volumio[1043]: info: Successfully Updated MyVolumio device Dec 05 21:47:23 volumio volumio[1043]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Dec 05 21:47:23 volumio sudo[3131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 05 21:47:23 volumio volumio[1043]: info: MPD Permissions set Dec 05 21:47:23 volumio sudo[3131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:23 volumio sudo[3133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 05 21:47:23 volumio sudo[3133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:23 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 21:47:23 volumio volumio[1043]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 05 21:47:23 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 05 21:47:23 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 05 21:47:23 volumio volumio[1043]: info: Received Get System Version Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 05 21:47:23 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:23 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:23 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:23 volumio volumio[1043]: info: Executing endpoint qc_getconfig Dec 05 21:47:23 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 21:47:23 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 05 21:47:23 volumio sudo[3131]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:23 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 05 21:47:23 volumio sudo[3133]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:23 volumio volumiobt[3145]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 05 21:47:23 volumio autossh[3140]: port set to 0, monitoring disabled Dec 05 21:47:23 volumio autossh[3140]: starting ssh (count 1) Dec 05 21:47:23 volumio autossh[3140]: ssh child pid is 3146 Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.007 [3129.3129] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio sudo[3147]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 05 21:47:24 volumio sudo[3147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:24 volumio sudo[3147]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:24 volumio volumio[1043]: error: updateQueue error: null Dec 05 21:47:24 volumio sudo[3154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 05 21:47:24 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:24 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:24 volumio volumiobt[3156]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 05 21:47:24 volumio volumio[1043]: info: Successfully Updated MyVolumio device Dec 05 21:47:24 volumio volumio[1043]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 21:47:24 volumio volumio[1043]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 21:47:24 volumio volumio[1043]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::volumioStop Dec 05 21:47:24 volumio volumio[1043]: info: CoreStateMachine::stop Dec 05 21:47:24 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 21:47:24 volumio volumio[1043]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 05 21:47:24 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 21:47:24 volumio volumio[1043]: info: Remote SSH Started Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.288 [3129.3129] INFO VolumeManager: [0x186178]: Setting new playback volume: 75 Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.289 [3129.3129] INFO VolumeManager: [0x186178]: Setting new mute state: 0 Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.290 [3129.3129] INFO QobuzConnect: [0x186b48]: Client initialized! Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.291 [3129.3129] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 05 21:47:24 volumio bluetoothd[790]: Adv Monitor app :1.22 disconnected from D-Bus Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.401 [3129.3129] INFO LocalConfigManager: [0x185a58]: Starting Local Configuration server Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.402 [3129.3129] INFO SampleApp: Starting Local configuration server Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.403 [3129.3129] INFO SampleApp: Playback volume changed: 75 Dec 05 21:47:24 volumio qobuz-connect[3129]: 20251205 21:47:24.403 [3129.3129] INFO SampleApp: Connected to UNIX socket client 0x170818 Dec 05 21:47:24 volumio bluetoothd[790]: Failed to set mode: Failed (0x03) Dec 05 21:47:24 volumio volumiobt[3159]: [NEW] Media /org/bluez/hci0 Dec 05 21:47:24 volumio volumiobt[3159]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 05 21:47:24 volumio volumiobt[3159]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 05 21:47:24 volumio volumiobt[3159]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 05 21:47:24 volumio volumiobt[3159]: Failed to set power on: org.bluez.Error.Failed Dec 05 21:47:24 volumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.24 Dec 05 21:47:24 volumio bluetoothd[790]: Adv Monitor app :1.24 disconnected from D-Bus Dec 05 21:47:24 volumio volumiossh-tunnel[3146]: Warning: Permanently added '[eu11.myvolumio.org]:2222' (ED25519) to the list of known hosts. Dec 05 21:47:24 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:24 volumio volumio[1043]: info: Starting Shairport Sync Dec 05 21:47:24 volumio volumio[1043]: 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 05 21:47:24 volumio volumio[1043]: info: Preparing to generate the ALSA configuration file Dec 05 21:47:24 volumio sudo[3164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 05 21:47:24 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 05 21:47:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 05 21:47:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 21:47:24 volumio systemd[1]: shairport-sync.service: Consumed 2.696s CPU time. Dec 05 21:47:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 21:47:25 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:25 volumio volumio[1043]: info: Shairport-Sync Started Dec 05 21:47:25 volumio volumio[1043]: info: Asound.conf file unchanged, so no further update is needed Dec 05 21:47:25 volumio volumio[1043]: info: Output device has changed, restarting MPD Dec 05 21:47:25 volumio volumio[1043]: info: Output device has changed, restarting Shairport Sync Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:25 volumio sudo[3183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 21:47:25 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:25 volumio sudo[3174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 21:47:25 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:25 volumio volumio[1043]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 05 21:47:25 volumio volumio[1043]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumiossh-tunnel[3146]: Warning: remote port forwarding failed for listen port 8091 Dec 05 21:47:25 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:25 volumio volumio[1043]: info: QobuzConnect: setDeactiveState invoked Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:25 volumio volumio[1043]: info: Preparing to generate the ALSA configuration file Dec 05 21:47:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 05 21:47:25 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 21:47:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 21:47:25 volumio systemd[1]: mpd.service: Consumed 3.185s CPU time. Dec 05 21:47:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Dec 05 21:47:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 21:47:25 volumio sudo[3198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 21:47:25 volumio sudo[3198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 21:47:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 21:47:25 volumio volumio[1043]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 05 21:47:25 volumio volumio[1043]: info: Reading ALSA contributions from plugins. Dec 05 21:47:25 volumio volumio[1043]: info: MPD Permissions set Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumiobt[3201]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 05 21:47:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 21:47:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:25 volumio go-librespot[3208]: go-librespot daemon starting... Dec 05 21:47:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:25 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:25 volumio sudo[3198]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:25 volumio volumiobt[3203]: [176B blob data] Dec 05 21:47:25 volumio volumio[1043]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xk8woo4xqBOn8eWMgXFelouONWO2.88b1fd2818c29a5bf1f21b1e06f7e27a.state.status' Dec 05 21:47:25 volumio volumio[1043]: info: Starting Shairport Sync Dec 05 21:47:25 volumio volumiobt[3203]: [157B blob data] Dec 05 21:47:25 volumio volumiobt[3203]: [157B blob data] Dec 05 21:47:25 volumio volumiobt[3203]: [157B blob data] Dec 05 21:47:25 volumio volumiobt[3203]: [113B blob data] Dec 05 21:47:25 volumio volumiobt[3203]: [bluetoothctl]> discoverable on Dec 05 21:47:25 volumio volumiobt[3203]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 05 21:47:25 volumio volumiobt[3203]: [bluetoothctl]> pairable on Dec 05 21:47:25 volumio go-librespot[3210]: time="2025-12-05T21:47:25+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:25 volumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.27 Dec 05 21:47:25 volumio go-librespot[3210]: time="2025-12-05T21:47:25+01:00" level=debug msg="app state loaded" Dec 05 21:47:25 volumio go-librespot[3210]: time="2025-12-05T21:47:25+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:25 volumio go-librespot[3210]: time="2025-12-05T21:47:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:25 volumio volumio[1043]: info: Asound.conf file written Dec 05 21:47:25 volumio bluetoothd[790]: Adv Monitor app :1.27 disconnected from D-Bus Dec 05 21:47:25 volumio volumiobt[3203]: [bluetoothctl]> Dec 05 21:47:25 volumio volumiobt[3221]: INFO [BTSTART] Registering Bluetooth agent... Dec 05 21:47:25 volumio sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 05 21:47:25 volumio sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:25 volumio sudo[3216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 21:47:25 volumio sudo[3216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:25 volumio sudo[3222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 05 21:47:25 volumio sudo[3222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:25 volumio volumiobt[3224]: [NEW] Media /org/bluez/hci0 Dec 05 21:47:25 volumio volumiobt[3224]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 05 21:47:25 volumio volumiobt[3224]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 05 21:47:25 volumio volumiobt[3224]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 05 21:47:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 05 21:47:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 05 21:47:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 21:47:25 volumio bluetoothd[790]: Adv Monitor app :1.28 disconnected from D-Bus Dec 05 21:47:25 volumio sudo[3222]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:25 volumio volumiobt[3227]: No agent is registered Dec 05 21:47:25 volumio volumiobt[3227]: [NEW] Media /org/bluez/hci0 Dec 05 21:47:25 volumio volumiobt[3227]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 05 21:47:25 volumio volumiobt[3227]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 05 21:47:25 volumio volumiobt[3227]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 05 21:47:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 21:47:25 volumio bluetoothd[790]: Adv Monitor app :1.29 disconnected from D-Bus Dec 05 21:47:25 volumio volumio[1043]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 05 21:47:25 volumio volumio[1043]: No state is present for card b1 Dec 05 21:47:25 volumio volumio[1043]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 05 21:47:25 volumio volumio[1043]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 05 21:47:25 volumio volumio[1043]: No state is present for card b1 Dec 05 21:47:25 volumio volumio[1043]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 05 21:47:25 volumio volumio[1043]: No state is present for card Headphones Dec 05 21:47:25 volumio volumio[1043]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 05 21:47:25 volumio volumio[1043]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 05 21:47:25 volumio volumio[1043]: No state is present for card Headphones Dec 05 21:47:25 volumio volumio[1043]: No state is present for card CODEC Dec 05 21:47:25 volumio volumio[1043]: Found hardware: "USB-Audio" "USB Mixer" "USB08bb:2900" "" "" Dec 05 21:47:25 volumio volumio[1043]: Hardware is initialized using a generic method Dec 05 21:47:25 volumio volumio[1043]: No state is present for card CODEC Dec 05 21:47:25 volumio volumio[1043]: info: Output device has changed, restarting MPD Dec 05 21:47:25 volumio volumiobt[3233]: INFO [BTSTART] Agent registered successfully. Dec 05 21:47:25 volumio sudo[3218]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:25 volumio sudo[3209]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 21:47:25 volumio qobuz-connect[3129]: 20251205 21:47:25.964 [3129.3129] INFO SampleApp: Stopping Local configuration server Dec 05 21:47:25 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 21:47:25 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 05 21:47:25 volumio volumiobt[3234]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 05 21:47:25 volumio sudo[3209]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio volumio[1043]: info: Output device has changed, restarting Shairport Sync Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:26 volumio sudo[3237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 05 21:47:26 volumio sudo[3237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio sudo[3237]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 05 21:47:26 volumio sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio volumio[1043]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 05 21:47:26 volumio volumio[1043]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio go-librespot[3210]: time="2025-12-05T21:47:26+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:25+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:26 volumio volumio[1043]: info: QobuzConnect: setDeactiveState invoked Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:26 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 05 21:47:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 05 21:47:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 05 21:47:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 05 21:47:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 05 21:47:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 05 21:47:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 05 21:47:26 volumio sudo[3271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 05 21:47:26 volumio volumio[1043]: info: MPD Permissions set Dec 05 21:47:26 volumio volumio[1043]: info: TidalConnect service stoped! Dec 05 21:47:26 volumio sudo[3271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio volumio[1043]: info: Shairport-Sync Started Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio sudo[3271]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 05 21:47:26 volumio sudo[3282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 05 21:47:26 volumio sudo[3282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio volumio[1043]: info: Starting Shairport Sync Dec 05 21:47:26 volumio qobuz-connect[3129]: 20251205 21:47:26.413 [3129.3129] INFO SampleApp: shat down connection on UNIX socket Dec 05 21:47:26 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 05 21:47:26 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 05 21:47:26 volumio volumio[1043]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 05 21:47:26 volumio volumio[1043]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 05 21:47:26 volumio volumio[1043]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 05 21:47:26 volumio volumio[1043]: info: QobuzConnect: setDeactiveState invoked Dec 05 21:47:26 volumio sudo[3290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 05 21:47:26 volumio sudo[3290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:26 volumio sudo[3269]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 05 21:47:26 volumio sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 21:47:26 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 05 21:47:26 volumio sudo[3269]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio sudo[3216]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 05 21:47:26 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 05 21:47:26 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 05 21:47:26 volumio sudo[3295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 05 21:47:26 volumio sudo[3295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 05 21:47:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 05 21:47:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 05 21:47:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 21:47:26 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 21:47:26 volumio sudo[3282]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio sudo[3290]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:26 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 05 21:47:26 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:26 volumio volumio[1043]: info: Shairport-Sync Started Dec 05 21:47:26 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 05 21:47:26 volumio sudo[3299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 21:47:26 volumio sudo[3299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:26 volumio sudo[3295]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:27 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 05 21:47:27 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 05 21:47:27 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 21:47:27 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 21:47:27 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 21:47:27 volumio volumio[1043]: info: Executing endpoint qc_getconfig Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.352 [3296.3296] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.369 [3296.3296] INFO VolumeManager: [0x150f178]: Setting new playback volume: 75 Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.380 [3296.3296] INFO VolumeManager: [0x150f178]: Setting new mute state: 0 Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.380 [3296.3296] INFO QobuzConnect: [0x150fb48]: Client initialized! Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.380 [3296.3296] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 05 21:47:27 volumio volumio[1043]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 05 21:47:27 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:27 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.487 [3296.3296] INFO LocalConfigManager: [0x150ea58]: Starting Local Configuration server Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.487 [3296.3296] INFO SampleApp: Starting Local configuration server Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.488 [3296.3296] INFO SampleApp: Connected to UNIX socket client 0x14f9818 Dec 05 21:47:27 volumio qobuz-connect[3296]: 20251205 21:47:27.502 [3296.3296] INFO SampleApp: Playback volume changed: 75 Dec 05 21:47:27 volumio volumio[1043]: info: Listing playlists Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 05 21:47:27 volumio volumio[1043]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 05 21:47:27 volumio volumio[1043]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:27 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:27 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:27 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:28 volumio volumio[1043]: info: Executing endpoint tc_getconfig Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 05 21:47:28 volumio vtcs[3310]: STARTING TidalConnect services, version: 1.5.2.56 Dec 05 21:47:28 volumio vtcs[3310]: STARTED TidalConnect services. Dec 05 21:47:28 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:28 volumio volumio[1043]: info: Executing endpoint tc_connect Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 05 21:47:28 volumio volumio[1043]: info: Connecting to TidalConnect Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:28 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::pushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output update for this device Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:28 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 05 21:47:28 volumio volumio[1043]: verbose: CURRENT POSITION 0 Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::pushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output update for this device Dec 05 21:47:28 volumio volumiobt[3238]: INFO:a2dp-agent:Connecting to system bus. Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: No code Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::pushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output update for this device Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Dec 05 21:47:28 volumio volumiobt[3238]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 05 21:47:28 volumio volumiobt[3238]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::pushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output update for this device Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Dec 05 21:47:28 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 05 21:47:28 volumio volumio[1043]: verbose: CURRENT POSITION 0 Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::pushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output update for this device Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: No code Dec 05 21:47:28 volumio volumio[1043]: info: CoreStateMachine::pushState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output update for this device Dec 05 21:47:28 volumio volumio[1043]: info: MRS: Pushing multiroomSync output Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumiobt[3238]: INFO:a2dp-agent:Registering agent. Dec 05 21:47:28 volumio volumiobt[3238]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 05 21:47:28 volumio volumiobt[3238]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 05 21:47:28 volumio volumio[1043]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xk8woo4xqBOn8eWMgXFelouONWO2.88b1fd2818c29a5bf1f21b1e06f7e27a.state.status' Dec 05 21:47:28 volumio volumio[1043]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xk8woo4xqBOn8eWMgXFelouONWO2.88b1fd2818c29a5bf1f21b1e06f7e27a.state.status' Dec 05 21:47:28 volumio volumio[1043]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xk8woo4xqBOn8eWMgXFelouONWO2.88b1fd2818c29a5bf1f21b1e06f7e27a.state.status' Dec 05 21:47:28 volumio volumio[1043]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xk8woo4xqBOn8eWMgXFelouONWO2.88b1fd2818c29a5bf1f21b1e06f7e27a.state.status' Dec 05 21:47:28 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:28 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:28 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:28 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:29 volumio sudo[3341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:29 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:29 volumio sudo[3342]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:29 volumio sudo[3342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:29 volumio sudo[3342]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:29 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio go-librespot[3343]: go-librespot daemon starting... Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio go-librespot[3346]: time="2025-12-05T21:47:29+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:29 volumio go-librespot[3346]: time="2025-12-05T21:47:29+01:00" level=debug msg="app state loaded" Dec 05 21:47:29 volumio go-librespot[3346]: time="2025-12-05T21:47:29+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:29 volumio go-librespot[3346]: time="2025-12-05T21:47:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:29 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:29 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:29 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:29 volumio go-librespot[3346]: time="2025-12-05T21:47:29+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:29+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:30 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:30 volumio volumio[1043]: info: TidalConnect service started! Dec 05 21:47:30 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:30 volumio sudo[3354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:30 volumio sudo[3354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3354]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio sudo[3356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:30 volumio sudo[3356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3356]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:30 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 05 21:47:30 volumio volumio[1043]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 05 21:47:30 volumio volumio[1043]: info: Executing endpoint metavolumio Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 05 21:47:30 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:30 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: Listing playlists Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 05 21:47:30 volumio sudo[3374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 05 21:47:30 volumio sudo[3374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 05 21:47:30 volumio sudo[3379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3385]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 05 21:47:30 volumio sudo[3385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3385]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio sudo[3379]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio sudo[3390]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 05 21:47:30 volumio sudo[3390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3374]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio sudo[3390]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio sudo[3398]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:30 volumio sudo[3398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3398]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio sudo[3402]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:30 volumio sudo[3402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:30 volumio sudo[3402]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:30 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:30 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:30 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227:3000 from 192.168.1.202 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:47:30 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:47:31 volumio sudo[3406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:31 volumio sudo[3406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:31 volumio sudo[3406]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:31 volumio sudo[3408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:31 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:31 volumio sudo[3408]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:31 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 05 21:47:31 volumio mpd[3293]: 2025-12-05T21:47:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 05 21:47:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 05 21:47:31 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:31 volumio sudo[3249]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:31 volumio volumio[1043]: error: MPD error: The expression evaluated to a falsy value: Dec 05 21:47:31 volumio volumio[1043]: assert.ok(self.idling) Dec 05 21:47:31 volumio volumio[1043]: error: The expression evaluated to a falsy value: Dec 05 21:47:31 volumio volumio[1043]: assert.ok(self.idling) Dec 05 21:47:31 volumio volumio[1043]: error: updateQueue error: null Dec 05 21:47:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 21:47:31 volumio sudo[3299]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:31 volumio mpd_monitor.sh[3413]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 21:47:31 volumio volumio[1043]: info: Successfully started MPD Monitor Dec 05 21:47:31 volumio sudo[3418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 05 21:47:31 volumio sudo[3418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:31 volumio sudo[3420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 21:47:31 volumio sudo[3420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:31 volumio sudo[3418]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:31 volumio sudo[3420]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:32 volumio volumio[1043]: verbose: New Socket.io Connection to 192.168.1.227 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 05 21:47:32 volumio volumio[1043]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 05 21:47:32 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:32 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:32 volumio volumio[1043]: info: Listing playlists Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 21:47:32 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 05 21:47:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Dec 05 21:47:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:33 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:33 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:33 volumio go-librespot[3423]: go-librespot daemon starting... Dec 05 21:47:33 volumio go-librespot[3424]: time="2025-12-05T21:47:33+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:33 volumio go-librespot[3424]: time="2025-12-05T21:47:33+01:00" level=debug msg="app state loaded" Dec 05 21:47:33 volumio go-librespot[3424]: time="2025-12-05T21:47:33+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:33 volumio go-librespot[3424]: time="2025-12-05T21:47:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:33 volumio go-librespot[3424]: time="2025-12-05T21:47:33+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:33+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:33 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 05 21:47:33 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 21:47:33 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:33 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:33 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:33 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:33 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:33 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:34 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 05 21:47:34 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:34 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:34 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:34 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:34 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:34 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:34 volumio sudo[3433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 21:47:34 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:34 volumio sudo[3435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 05 21:47:34 volumio sudo[3435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:34 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 05 21:47:34 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 05 21:47:34 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 05 21:47:34 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 05 21:47:34 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:34 volumio sudo[3435]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:34 volumio mpd_monitor.sh[3438]: MPD Monitor Service: Starting MPD Monitor Service Dec 05 21:47:34 volumio volumio[1043]: info: Successfully started MPD Monitor Dec 05 21:47:34 volumio volumio[1043]: info: Successfully started MPD Monitor Dec 05 21:47:35 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:35 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 05 21:47:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 05 21:47:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 05 21:47:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 05 21:47:36 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:36 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:36 volumio volumio[1043]: info: Received Get System Version Dec 05 21:47:36 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 05 21:47:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Dec 05 21:47:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:36 volumio go-librespot[3462]: go-librespot daemon starting... Dec 05 21:47:36 volumio go-librespot[3463]: time="2025-12-05T21:47:36+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:36 volumio go-librespot[3463]: time="2025-12-05T21:47:36+01:00" level=debug msg="app state loaded" Dec 05 21:47:36 volumio go-librespot[3463]: time="2025-12-05T21:47:36+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:36 volumio go-librespot[3463]: time="2025-12-05T21:47:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:36 volumio go-librespot[3463]: time="2025-12-05T21:47:36+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:36+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:39 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:39 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:39 volumio kernel: hwmon hwmon1: Voltage normalised Dec 05 21:47:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Dec 05 21:47:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:40 volumio go-librespot[3471]: go-librespot daemon starting... Dec 05 21:47:40 volumio go-librespot[3472]: time="2025-12-05T21:47:40+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:40 volumio go-librespot[3472]: time="2025-12-05T21:47:40+01:00" level=debug msg="app state loaded" Dec 05 21:47:40 volumio go-librespot[3472]: time="2025-12-05T21:47:40+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:40 volumio go-librespot[3472]: time="2025-12-05T21:47:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:40 volumio go-librespot[3472]: time="2025-12-05T21:47:40+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:40+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:42 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 05 21:47:42 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:42 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Dec 05 21:47:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:43 volumio go-librespot[3494]: go-librespot daemon starting... Dec 05 21:47:43 volumio go-librespot[3495]: time="2025-12-05T21:47:43+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:43 volumio go-librespot[3495]: time="2025-12-05T21:47:43+01:00" level=debug msg="app state loaded" Dec 05 21:47:43 volumio go-librespot[3495]: time="2025-12-05T21:47:43+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:43 volumio go-librespot[3495]: time="2025-12-05T21:47:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:43 volumio go-librespot[3495]: time="2025-12-05T21:47:43+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:43+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:45 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:45 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Dec 05 21:47:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:47 volumio go-librespot[3502]: go-librespot daemon starting... Dec 05 21:47:47 volumio go-librespot[3503]: time="2025-12-05T21:47:47+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:47 volumio go-librespot[3503]: time="2025-12-05T21:47:47+01:00" level=debug msg="app state loaded" Dec 05 21:47:47 volumio go-librespot[3503]: time="2025-12-05T21:47:47+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:47 volumio go-librespot[3503]: time="2025-12-05T21:47:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:47 volumio go-librespot[3503]: time="2025-12-05T21:47:47+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:47+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:48 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:48 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:48 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 05 21:47:50 volumio volumio[1043]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 05 21:47:50 volumio volumio[1043]: info: Received Get System Version Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 05 21:47:50 volumio volumio[1043]: info: Received Get System Info Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:47:50 volumio volumio[1043]: info: Discovery: Getting this device information Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Dec 05 21:47:50 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:47:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88. Dec 05 21:47:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:50 volumio go-librespot[3526]: go-librespot daemon starting... Dec 05 21:47:50 volumio go-librespot[3527]: time="2025-12-05T21:47:50+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:50 volumio go-librespot[3527]: time="2025-12-05T21:47:50+01:00" level=debug msg="app state loaded" Dec 05 21:47:50 volumio go-librespot[3527]: time="2025-12-05T21:47:50+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:50 volumio go-librespot[3527]: time="2025-12-05T21:47:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:50 volumio go-librespot[3527]: time="2025-12-05T21:47:50+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:50+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:51 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:51 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89. Dec 05 21:47:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:54 volumio go-librespot[3534]: go-librespot daemon starting... Dec 05 21:47:54 volumio go-librespot[3535]: time="2025-12-05T21:47:54+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:54 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:54 volumio go-librespot[3535]: time="2025-12-05T21:47:54+01:00" level=debug msg="app state loaded" Dec 05 21:47:54 volumio go-librespot[3535]: time="2025-12-05T21:47:54+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:54 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:54 volumio go-librespot[3535]: time="2025-12-05T21:47:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:54 volumio go-librespot[3535]: time="2025-12-05T21:47:54+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:54+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:54 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Dec 05 21:47:54 volumio volumio[1043]: info: MRS: Starting browser stream Dec 05 21:47:54 volumio volumio[1043]: info: MRS: Setting this device as Streaming Server Dec 05 21:47:54 volumio volumio[1043]: info: Dec 05 21:47:54 volumio volumio[1043]: [1764967674451] ---------------------------- MRS: Setting Streaming Server Dec 05 21:47:54 volumio volumio[1043]: info: Enabled audio output: browserPlayback Dec 05 21:47:54 volumio volumio[1043]: info: MRS: enable multiroom server output Dec 05 21:47:54 volumio volumio[1043]: info: MRS: Set multiroom target PCM to volumioMultiRoom Dec 05 21:47:54 volumio volumio[1043]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Dec 05 21:47:54 volumio volumio[1043]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Dec 05 21:47:54 volumio volumio[1043]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Dec 05 21:47:54 volumio volumio[1043]: info: MRS: STARTING volumioStreaming Dec 05 21:47:54 volumio sudo[3545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 05 21:47:54 volumio sudo[3545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:54 volumio sudo[3545]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:54 volumio sudo[3547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Dec 05 21:47:54 volumio sudo[3547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:54 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Dec 05 21:47:54 volumio sudo[3547]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:54 volumio volumio[1043]: info: MRS: volumioStreaming STARTED Dec 05 21:47:54 volumio sudo[3551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 05 21:47:54 volumio sudo[3551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:54 volumio sudo[3551]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:55 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Dec 05 21:47:55 volumio volumio[1043]: info: MRS: Starting browser stream Dec 05 21:47:55 volumio volumio[1043]: info: MRS: Setting this device as single Dec 05 21:47:55 volumio volumio[1043]: info: Dec 05 21:47:55 volumio volumio[1043]: [1764967675750] ---------------------------- Setting Multiroom Single Dec 05 21:47:55 volumio volumio[1043]: info: MRS: disable multiroom output Dec 05 21:47:55 volumio volumio[1043]: info: MRS: Set multiroom target PCM to volumioDiscard Dec 05 21:47:55 volumio volumio[1043]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Dec 05 21:47:56 volumio volumio[1043]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Dec 05 21:47:56 volumio volumio[1043]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Dec 05 21:47:57 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:47:57 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:47:57 volumio volumio[1043]: info: MRS: STOPPING volumioStreaming Dec 05 21:47:57 volumio sudo[3560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 05 21:47:57 volumio sudo[3560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:57 volumio sudo[3562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 05 21:47:57 volumio sudo[3562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:47:57 volumio sudo[3562]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:57 volumio volumio[1043]: info: MRS: Removed streaming files Dec 05 21:47:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90. Dec 05 21:47:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:47:57 volumio go-librespot[3566]: go-librespot daemon starting... Dec 05 21:47:57 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Dec 05 21:47:57 volumio systemd[1]: volumioStreaming.service: Killing process 3553 (ffmpeg) with signal SIGKILL. Dec 05 21:47:57 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. Dec 05 21:47:57 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Dec 05 21:47:57 volumio go-librespot[3567]: time="2025-12-05T21:47:57+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:47:57 volumio go-librespot[3567]: time="2025-12-05T21:47:57+01:00" level=debug msg="app state loaded" Dec 05 21:47:57 volumio go-librespot[3567]: time="2025-12-05T21:47:57+01:00" level=debug msg="stored credentials not found" Dec 05 21:47:57 volumio systemd[1]: volumioStreaming.service: Consumed 2.194s CPU time. Dec 05 21:47:57 volumio go-librespot[3567]: time="2025-12-05T21:47:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:47:57 volumio sudo[3560]: pam_unix(sudo:session): session closed for user root Dec 05 21:47:57 volumio volumio[1043]: info: MRS: volumioStreaming STOPPED Dec 05 21:47:57 volumio go-librespot[3567]: time="2025-12-05T21:47:57+01:00" level=fatal msg="failed running zeroconf" 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-05T21:47:57+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:47:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:47:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:58 volumio volumio[1043]: info: Retrieving Cloud Streaming UI Dec 05 21:47:58 volumio volumio[1043]: info: Getting Tidal Cloud Configuration Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:58 volumio volumio[1043]: info: Getting Qobuz Cloud Configuration Dec 05 21:47:58 volumio volumio[1043]: info: Asking plugin for UI Config Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:58 volumio volumio[1043]: info: Getting Spotify Cloud Configuration Dec 05 21:47:58 volumio volumio[1043]: info: Asking plugin for UI Config Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:58 volumio volumio[1043]: info: Saving Spotify Acccount Dec 05 21:47:58 volumio volumio[1043]: info: Got it Dec 05 21:47:58 volumio volumio[1043]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 05 21:47:58 volumio volumio[1043]: info: Got it Dec 05 21:47:58 volumio volumio[1043]: info: Got Tidal Cloud Configuration Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Dec 05 21:47:58 volumio volumio[1043]: info: CoreCommandRouter::volumioGetBrowseSources Dec 05 21:47:59 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 05 21:47:59 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 05 21:48:00 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:48:00 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:48:00 volumio kernel: hwmon hwmon1: Voltage normalised Dec 05 21:48:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91. Dec 05 21:48:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:00 volumio go-librespot[3592]: go-librespot daemon starting... Dec 05 21:48:00 volumio go-librespot[3593]: time="2025-12-05T21:48:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:48:00 volumio go-librespot[3593]: time="2025-12-05T21:48:00+01:00" level=debug msg="app state loaded" Dec 05 21:48:00 volumio go-librespot[3593]: time="2025-12-05T21:48:00+01:00" level=debug msg="stored credentials not found" Dec 05 21:48:00 volumio go-librespot[3593]: time="2025-12-05T21:48:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:48:01 volumio go-librespot[3593]: time="2025-12-05T21:48:01+01:00" level=fatal msg="failed running zeroconf" 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-05T21:48:00+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:48:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:48:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:48:02 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 05 21:48:03 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:48:03 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:48:03 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 05 21:48:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 92. Dec 05 21:48:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:04 volumio go-librespot[3601]: go-librespot daemon starting... Dec 05 21:48:04 volumio go-librespot[3602]: time="2025-12-05T21:48:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:48:04 volumio go-librespot[3602]: time="2025-12-05T21:48:04+01:00" level=debug msg="app state loaded" Dec 05 21:48:04 volumio go-librespot[3602]: time="2025-12-05T21:48:04+01:00" level=debug msg="stored credentials not found" Dec 05 21:48:04 volumio go-librespot[3602]: time="2025-12-05T21:48:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:48:04 volumio go-librespot[3602]: time="2025-12-05T21:48:04+01:00" level=fatal msg="failed running zeroconf" 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-05T21:48:04+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:48:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:48:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:48:06 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:48:06 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 05 21:48:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 05 21:48:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 93. Dec 05 21:48:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:07 volumio go-librespot[3617]: go-librespot daemon starting... Dec 05 21:48:07 volumio go-librespot[3618]: time="2025-12-05T21:48:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:48:07 volumio go-librespot[3618]: time="2025-12-05T21:48:07+01:00" level=debug msg="app state loaded" Dec 05 21:48:07 volumio go-librespot[3618]: time="2025-12-05T21:48:07+01:00" level=debug msg="stored credentials not found" Dec 05 21:48:07 volumio go-librespot[3618]: time="2025-12-05T21:48:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:48:08 volumio go-librespot[3618]: time="2025-12-05T21:48:08+01:00" level=fatal msg="failed running zeroconf" 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-05T21:48:07+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:48:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:48:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:48:09 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:48:09 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:48:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94. Dec 05 21:48:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:11 volumio go-librespot[3639]: go-librespot daemon starting... Dec 05 21:48:11 volumio go-librespot[3640]: time="2025-12-05T21:48:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:48:11 volumio go-librespot[3640]: time="2025-12-05T21:48:11+01:00" level=debug msg="app state loaded" Dec 05 21:48:11 volumio go-librespot[3640]: time="2025-12-05T21:48:11+01:00" level=debug msg="stored credentials not found" Dec 05 21:48:11 volumio go-librespot[3640]: time="2025-12-05T21:48:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:48:11 volumio go-librespot[3640]: time="2025-12-05T21:48:11+01:00" level=fatal msg="failed running zeroconf" 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-05T21:48:11+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:48:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:48:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:48:12 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:48:12 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:48:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95. Dec 05 21:48:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:15 volumio go-librespot[3650]: go-librespot daemon starting... Dec 05 21:48:15 volumio go-librespot[3651]: time="2025-12-05T21:48:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:48:15 volumio go-librespot[3651]: time="2025-12-05T21:48:15+01:00" level=debug msg="app state loaded" Dec 05 21:48:15 volumio go-librespot[3651]: time="2025-12-05T21:48:15+01:00" level=debug msg="stored credentials not found" Dec 05 21:48:15 volumio go-librespot[3651]: time="2025-12-05T21:48:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:48:15 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Dec 05 21:48:15 volumio go-librespot[3651]: time="2025-12-05T21:48:15+01:00" level=debug msg="new websocket client" Dec 05 21:48:15 volumio volumio[1043]: info: Connection to go-librespot Websocket established Dec 05 21:48:15 volumio go-librespot[3651]: time="2025-12-05T21:48:15+01:00" level=fatal msg="failed running zeroconf" 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-05T21:48:15+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:48:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:48:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:48:15 volumio volumio[1043]: info: Connection to go-librespot Websocket closed Dec 05 21:48:18 volumio volumio[1043]: info: Getting Spotify volume Dec 05 21:48:18 volumio volumio[1043]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 21:48:18 volumio volumio[1043]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:48:18 volumio volumio[1043]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 05 21:48:18 volumio volumio[1043]: errno: -111, Dec 05 21:48:18 volumio volumio[1043]: code: 'ECONNREFUSED', Dec 05 21:48:18 volumio volumio[1043]: syscall: 'connect', Dec 05 21:48:18 volumio volumio[1043]: address: '127.0.0.1', Dec 05 21:48:18 volumio volumio[1043]: port: 9879, Dec 05 21:48:18 volumio volumio[1043]: response: undefined Dec 05 21:48:18 volumio volumio[1043]: } Dec 05 21:48:18 volumio volumio[1043]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 21:48:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Dec 05 21:48:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:48:18 volumio go-librespot[3671]: go-librespot daemon starting... Dec 05 21:48:18 volumio go-librespot[3672]: time="2025-12-05T21:48:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:48:18 volumio go-librespot[3672]: time="2025-12-05T21:48:18+01:00" level=debug msg="app state loaded" Dec 05 21:48:18 volumio go-librespot[3672]: time="2025-12-05T21:48:18+01:00" level=debug msg="stored credentials not found" Dec 05 21:48:18 volumio go-librespot[3672]: time="2025-12-05T21:48:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:48:18 volumio go-librespot[3672]: time="2025-12-05T21:48:18+01:00" level=fatal msg="failed running zeroconf" 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-05T21:48:18+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:48:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:48:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:48:19 volumio sudo[3681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 21:47' Dec 05 21:48:19 volumio sudo[3681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"