Dec 12 18:06:02 volumio2 volumio[1648]: info: getStreamUrl took 13426 milliseconds Dec 12 18:06:02 volumio2 volumio[1648]: error: Could not get TIDAL Stream URL: Could not get TIDAL Stream URL Dec 12 18:06:29 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d4:da:21:79:ec:10 reason=2 Dec 12 18:06:29 volumio2 dhcpcd[1027]: wlan0: carrier lost Dec 12 18:06:29 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 12 18:06:29 volumio2 avahi-daemon[2869]: Withdrawing address record for 192.168.31.92 on wlan0. Dec 12 18:06:29 volumio2 avahi-daemon[2869]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.92. Dec 12 18:06:29 volumio2 dhcpcd[1027]: wlan0: deleting route to 192.168.31.0/24 Dec 12 18:06:29 volumio2 dhcpcd[1027]: wlan0: deleting default route via 192.168.31.1 Dec 12 18:06:29 volumio2 avahi-daemon[2869]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 12 18:06:29 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:06:29 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 12 18:06:29 volumio2 systemd[1]: welcome.service: Deactivated successfully. Dec 12 18:06:29 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 12 18:06:29 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 12 18:06:29 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 12 18:06:29 volumio2 welcome[5762]: Resolved ip:[0] Dec 12 18:06:29 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 12 18:06:29 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:06:30 volumio2 ntpd[1149]: IO: Deleting interface #5 wlan0, 192.168.31.92#123, interface stats: received=132, sent=155, dropped=2, active_time=311 secs Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 78.83.137.244 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 78.130.168.61 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 82.118.235.227 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 195.238.84.84 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 195.238.84.23 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 95.43.236.49 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 176.12.60.87 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 195.85.215.215 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 84.54.128.102 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 88.203.195.14 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 151.251.152.123 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 195.85.215.8 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 162.159.200.123 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 185.117.82.66 unlink local addr 192.168.31.92 -> Dec 12 18:06:30 volumio2 ntpd[1149]: PROTO: 78.159.131.45 unlink local addr 192.168.31.92 -> Dec 12 18:06:31 volumio2 wpa_supplicant[2732]: wlan0: Trying to associate with d4:da:21:79:ec:0f (SSID='SUVODOL WIFI' freq=2412 MHz) Dec 12 18:06:32 volumio2 wpa_supplicant[2732]: wlan0: Associated with d4:da:21:79:ec:0f Dec 12 18:06:32 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 18:06:32 volumio2 wpa_supplicant[2732]: wlan0: WPA: Key negotiation completed with d4:da:21:79:ec:0f [PTK=CCMP GTK=CCMP] Dec 12 18:06:32 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:da:21:79:ec:0f completed [id=0 id_str=] Dec 12 18:06:32 volumio2 dhcpcd[1027]: wlan0: carrier acquired Dec 12 18:06:32 volumio2 dhcpcd[1027]: wlan0: IAID 67:ad:05:23 Dec 12 18:06:32 volumio2 dhcpcd[1027]: wlan0: soliciting an IPv6 router Dec 12 18:06:33 volumio2 wpa_supplicant[2732]: RRM: Ignoring radio measurement request: Not RRM network Dec 12 18:06:33 volumio2 wpa_supplicant[2732]: wlan0: WNM: Preferred List Available Dec 12 18:06:33 volumio2 wpa_supplicant[2732]: wlan0: Trying to associate with d4:da:21:79:e3:73 (SSID='SUVODOL WIFI' freq=5180 MHz) Dec 12 18:06:33 volumio2 dhcpcd[1027]: wlan0: carrier lost - roaming Dec 12 18:06:33 volumio2 kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Dec 12 18:06:33 volumio2 wpa_supplicant[2732]: wlan0: Associated with d4:da:21:79:e3:73 Dec 12 18:06:33 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 18:06:35 volumio2 wpa_supplicant[2732]: wlan0: WPA: Key negotiation completed with d4:da:21:79:e3:73 [PTK=CCMP GTK=CCMP] Dec 12 18:06:35 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:da:21:79:e3:73 completed [id=0 id_str=] Dec 12 18:06:35 volumio2 dhcpcd[1027]: wlan0: carrier acquired Dec 12 18:06:35 volumio2 dhcpcd[1027]: wlan0: IAID 67:ad:05:23 Dec 12 18:06:35 volumio2 dhcpcd[1027]: wlan0: soliciting an IPv6 router Dec 12 18:06:37 volumio2 volumio[1648]: info: Volumio Network Manager: Network status updated: 0 Dec 12 18:06:37 volumio2 dhcpcd[1027]: wlan0: rebinding lease of 192.168.31.92 Dec 12 18:06:38 volumio2 dhcpcd[1027]: wlan0: probing address 192.168.31.92/24 Dec 12 18:06:38 volumio2 wpa_supplicant[2732]: RRM: Ignoring radio measurement request: Not RRM network Dec 12 18:06:40 volumio2 volumio[1648]: info: Discovery: Disconnected from remote: 192.168.31.193 Dec 12 18:06:40 volumio2 volumio[1648]: info: MRS: Cleaning up device: dc46c9a3-6afc-47b4-85f3-1b4cea49eaa1 Dec 12 18:06:40 volumio2 volumio[1648]: info: Removing audio output: Dec 12 18:06:40 volumio2 volumio[1648]: info: Discovery: Remote connection error: 192.168.31.193 Dec 12 18:06:42 volumio2 kernel: usb 1-1: USB disconnect, device number 3 Dec 12 18:06:42 volumio2 volumio[1648]: info: Dec 12 18:06:42 volumio2 volumio[1648]: ---------------------------- USB Audio Device Detached Dec 12 18:06:42 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 12 18:06:42 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:06:42 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 12 18:06:43 volumio2 volumio[1648]: info: Discovery: Remote connection error: 192.168.31.193 Dec 12 18:06:43 volumio2 dhcpcd[1027]: wlan0: leased 192.168.31.92 for 43200 seconds Dec 12 18:06:43 volumio2 avahi-daemon[2869]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.92. Dec 12 18:06:43 volumio2 dhcpcd[1027]: wlan0: adding route to 192.168.31.0/24 Dec 12 18:06:43 volumio2 avahi-daemon[2869]: New relevant interface wlan0.IPv4 for mDNS. Dec 12 18:06:43 volumio2 avahi-daemon[2869]: Registering new address record for 192.168.31.92 on wlan0.IPv4. Dec 12 18:06:43 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:06:43 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 12 18:06:43 volumio2 systemd[1]: welcome.service: Deactivated successfully. Dec 12 18:06:43 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 12 18:06:43 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 12 18:06:43 volumio2 dhcpcd[1027]: wlan0: adding default route via 192.168.31.1 Dec 12 18:06:43 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 12 18:06:43 volumio2 welcome[5813]: Resolved ip:[1] 192.168.31.92 Dec 12 18:06:43 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 12 18:06:43 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:06:43 volumio2 wpa_supplicant[2732]: RRM: Ignoring radio measurement request: Not RRM network Dec 12 18:06:44 volumio2 ntpd[1149]: IO: Listen normally on 6 wlan0 192.168.31.92:123 Dec 12 18:06:44 volumio2 ntpd[1149]: IO: new interface(s) found: waking up resolver Dec 12 18:06:45 volumio2 dhcpcd[1027]: wlan0: carrier lost - roaming Dec 12 18:06:45 volumio2 dhcpcd[1027]: wlan0: changing route to 192.168.31.0/24 Dec 12 18:06:45 volumio2 dhcpcd[1027]: wlan0: changing default route via 192.168.31.1 Dec 12 18:06:45 volumio2 wpa_supplicant[2732]: wlan0: Associated with d4:da:21:79:ec:0f Dec 12 18:06:45 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 18:06:45 volumio2 wpa_supplicant[2732]: wlan0: WPA: Key negotiation completed with d4:da:21:79:ec:0f [PTK=CCMP GTK=CCMP] Dec 12 18:06:45 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:da:21:79:ec:0f completed [id=0 id_str=] Dec 12 18:06:45 volumio2 dhcpcd[1027]: wlan0: carrier acquired Dec 12 18:06:45 volumio2 dhcpcd[1027]: wlan0: IAID 67:ad:05:23 Dec 12 18:06:45 volumio2 dhcpcd[1027]: wlan0: soliciting an IPv6 router Dec 12 18:06:46 volumio2 volumio[1648]: info: MRS: Found cast device: SHIELD-Android-TV-0e9c52e15d2938bf7e3894ced96bee45 Dec 12 18:06:46 volumio2 dhcpcd[1027]: wlan0: rebinding lease of 192.168.31.92 Dec 12 18:06:46 volumio2 dhcpcd[1027]: wlan0: leased 192.168.31.92 for 43200 seconds Dec 12 18:06:46 volumio2 dhcpcd[1027]: wlan0: changing route to 192.168.31.0/24 Dec 12 18:06:46 volumio2 dhcpcd[1027]: wlan0: changing default route via 192.168.31.1 Dec 12 18:06:46 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:06:46 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 12 18:06:46 volumio2 systemd[1]: welcome.service: Deactivated successfully. Dec 12 18:06:46 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 12 18:06:46 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 12 18:06:46 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 12 18:06:46 volumio2 welcome[5864]: Resolved ip:[1] 192.168.31.92 Dec 12 18:06:46 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 12 18:06:46 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:06:47 volumio2 volumio[1648]: verbose: New Socket.io Connection to 192.168.31.92:3000 from 192.168.31.193 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 12 18:06:47 volumio2 volumio[1648]: info: Volumio Network Manager: Network status updated: 2 Dec 12 18:06:47 volumio2 sudo[5898]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 12 18:06:47 volumio2 sudo[5898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:47 volumio2 sudo[5898]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:47 volumio2 sudo[5900]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 12 18:06:47 volumio2 sudo[5900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:47 volumio2 sudo[5900]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:47 volumio2 volumio[1648]: verbose: New Socket.io Connection to 192.168.31.92 from 192.168.31.75 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 10 Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:47 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 18:06:47 volumio2 volumio[1648]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 12 18:06:47 volumio2 volumio[1648]: info: Received Get System Info Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 18:06:47 volumio2 volumio[1648]: info: Discovery: Getting this device information Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:47 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:47 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:47 volumio2 volumio[1648]: info: Listing playlists Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 12 18:06:47 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 18:06:48 volumio2 volumio[1648]: info: MRS: Pushing audio output on request Dec 12 18:06:48 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:06:48 volumio2 volumio[1648]: info: Discovery: Connected to remote: 192.168.31.193 Dec 12 18:06:48 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 12 18:06:48 volumio2 volumio[1648]: verbose: New Socket.io Connection to 192.168.31.92:3000 from 192.168.31.193 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 12 18:06:48 volumio2 volumio[1648]: info: MRS: Adding multiroomSync output Dec 12 18:06:48 volumio2 volumio[1648]: info: Adding audio output: Dec 12 18:06:48 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:06:48 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 12 18:06:48 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:48 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:48 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:48 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:49 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 12 18:06:49 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 18:06:49 volumio2 volumio[1648]: info: Received Get System Info Dec 12 18:06:49 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 18:06:49 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 18:06:49 volumio2 volumio[1648]: info: Discovery: Getting this device information Dec 12 18:06:49 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:49 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:49 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 18:06:50 volumio2 volumio[1648]: info: Executing endpoint metavolumio Dec 12 18:06:50 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 18:06:50 volumio2 volumio[1648]: info: Executing endpoint metavolumio Dec 12 18:06:50 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 18:06:50 volumio2 volumio[1648]: info: Executing endpoint metavolumio Dec 12 18:06:50 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 18:06:51 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 18:06:51 volumio2 volumio[1648]: info: Received Get System Info Dec 12 18:06:51 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 18:06:51 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 18:06:51 volumio2 volumio[1648]: info: Discovery: Getting this device information Dec 12 18:06:51 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:51 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:51 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 18:06:54 volumio2 kernel: hwmon hwmon3: Undervoltage detected! Dec 12 18:06:56 volumio2 kernel: hwmon hwmon3: Voltage normalised Dec 12 18:06:57 volumio2 kernel: usb 1-1: new high-speed USB device number 5 using xhci-hcd Dec 12 18:06:57 volumio2 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 6.f2 Dec 12 18:06:57 volumio2 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 12 18:06:57 volumio2 kernel: usb 1-1: Product: SMSL Sanskrit 10th Dec 12 18:06:57 volumio2 kernel: usb 1-1: Manufacturer: SMSL Dec 12 18:06:57 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 12 18:06:58 volumio2 (udev-worker)[5904]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 12 18:06:59 volumio2 volumio[1648]: info: Dec 12 18:06:59 volumio2 volumio[1648]: ---------------------------- USB Audio Device Attached Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::Close All Modals sent Dec 12 18:06:59 volumio2 volumio[1648]: info: Preparing to save Alsa Options, stopping services first Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:59 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPause Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreStateMachine::pause Dec 12 18:06:59 volumio2 volumio[1648]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL Sanskrit 10th","alsacard":"S10th"},"i2s":false} Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:06:59 volumio2 volumio[1648]: info: Setting default mixerSoftMaster for Softvolume device Dec 12 18:06:59 volumio2 volumio[1648]: info: QobuzConnect: setDeactiveState invoked Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:59 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:59 volumio2 vtcs[5561]: [2025-12-12 18:06:59.039] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 12 18:06:59 volumio2 vtcs[5561]: [2025-12-12 18:06:59.039] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 12 18:06:59 volumio2 sudo[5937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:06:59 volumio2 sudo[5937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 12 18:06:59 volumio2 volumio[1648]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 12 18:06:59 volumio2 volumio[1648]: info: Disabling external Volume Control Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:06:59 volumio2 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 12 18:06:59 volumio2 volumio[1648]: info: Enable softmixer device for audio device undefined Dec 12 18:06:59 volumio2 systemd[1]: vtcs.service: Deactivated successfully. Dec 12 18:06:59 volumio2 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 12 18:06:59 volumio2 sudo[5937]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 sudo[5950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:06:59 volumio2 sudo[5950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:06:59 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:06:59 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:06:59 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:59 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:59 volumio2 volumio[1648]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Dec 12 18:06:59 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:06:59 volumio2 sudo[5958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 18:06:59 volumio2 sudo[5950]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 sudo[5958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 sudo[5961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:06:59 volumio2 sudo[5961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 volumio[1648]: info: Software Volume ALSA configuration written Dec 12 18:06:59 volumio2 volumio[1648]: info: Preparing to generate the ALSA configuration file Dec 12 18:06:59 volumio2 sudo[5958]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 sudo[5964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 18:06:59 volumio2 sudo[5964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 18:06:59 volumio2 sudo[5961]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:06:59 volumio2 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 18:06:59 volumio2 qobuz-connect[5543]: 20251212 18:06:59.157 [5543.5543] INFO SampleApp: Stopping Local configuration server Dec 12 18:06:59 volumio2 volumio[1648]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 12 18:06:59 volumio2 volumio[1648]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 12 18:06:59 volumio2 volumio[1648]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 12 18:06:59 volumio2 volumio[1648]: info: Reading ALSA contributions from plugins. Dec 12 18:06:59 volumio2 volumio[1648]: info: peppyspectrum Daemon Stop Dec 12 18:06:59 volumio2 volumio[1648]: info: Asound.conf file unchanged, so no further update is needed Dec 12 18:06:59 volumio2 volumio[1648]: info: Output device has changed, restarting MPD Dec 12 18:06:59 volumio2 volumio[1648]: info: Output device has changed, restarting Shairport Sync Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:06:59 volumio2 sudo[5973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 18:06:59 volumio2 sudo[5973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 sudo[5973]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 sudo[5975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 18:06:59 volumio2 sudo[5975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 volumio[1648]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 18:06:59 volumio2 volumio[1648]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: QobuzConnect: setDeactiveState invoked Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:06:59 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:06:59 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 18:06:59 volumio2 sudo[5987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:06:59 volumio2 sudo[5987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 sudo[5985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:06:59 volumio2 sudo[5985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 12 18:06:59 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 18:06:59 volumio2 volumio[1648]: info: MPD Permissions set Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 sudo[5995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 18:06:59 volumio2 sudo[5995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 12 18:06:59 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:06:59 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:06:59 volumio2 volumio[1648]: info: Starting Shairport Sync Dec 12 18:06:59 volumio2 sudo[6004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 18:06:59 volumio2 sudo[6004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 18:06:59 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 18:06:59 volumio2 sudo[5995]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 18:06:59 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 18:06:59 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 18:06:59 volumio2 systemd[1]: shairport-sync.service: Consumed 1.573s CPU time. Dec 12 18:06:59 volumio2 sudo[6007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 18:06:59 volumio2 sudo[6007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:06:59 volumio2 sudo[5987]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 sudo[5985]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 sudo[6006]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 18:06:59 volumio2 sudo[6006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:06:59 volumio2 sudo[6006]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 18:06:59 volumio2 sudo[6004]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 volumio[1648]: info: Shairport-Sync Started Dec 12 18:06:59 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 12 18:06:59 volumio2 mpd[6013]: 2025-12-12T18:06:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 18:06:59 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 18:06:59 volumio2 sudo[5975]: pam_unix(sudo:session): session closed for user root Dec 12 18:06:59 volumio2 volumio[1648]: error: updateQueue error: null Dec 12 18:07:00 volumio2 qobuz-connect[5543]: 20251212 18:07:00.023 [5543.5543] INFO SampleApp: shat down connection on UNIX socket Dec 12 18:07:00 volumio2 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 12 18:07:00 volumio2 volumio[1648]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 12 18:07:00 volumio2 volumio[1648]: info: QobuzConnect: setDeactiveState invoked Dec 12 18:07:00 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:00 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:00 volumio2 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 18:07:00 volumio2 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 18:07:00 volumio2 sudo[5964]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:00 volumio2 sudo[6007]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:00 volumio2 volumio[1648]: info: Executing endpoint qc_getconfig Dec 12 18:07:00 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.066 [6030.6030] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.067 [6030.6030] INFO VolumeManager: [0x11c7168]: Setting new playback volume: 75 Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.067 [6030.6030] INFO VolumeManager: [0x11c7168]: Setting new mute state: 0 Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.067 [6030.6030] INFO QobuzConnect: [0x11c7b38]: Client initialized! Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.067 [6030.6030] INFO SampleApp: Starting Avahi advertising, name: Volumio2, service name: _qobuz-connect._tcp Dec 12 18:07:00 volumio2 volumio[1648]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 12 18:07:00 volumio2 volumio[1648]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.071 [6030.6030] INFO LocalConfigManager: [0x11c6a48]: Starting Local Configuration server Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.071 [6030.6030] INFO SampleApp: Starting Local configuration server Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.072 [6030.6030] INFO SampleApp: Connected to UNIX socket client 0x11b1818 Dec 12 18:07:00 volumio2 qobuz-connect[6030]: 20251212 18:07:00.216 [6030.6030] INFO SampleApp: Playback volume changed: 75 Dec 12 18:07:00 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:00 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:02 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:02 volumio2 volumio[1648]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:02 volumio2 volumio[1648]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:02 volumio2 sudo[6045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 18:07:02 volumio2 sudo[6045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:02 volumio2 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 12 18:07:02 volumio2 sudo[6045]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:02 volumio2 volumio[1648]: info: Executing endpoint tc_getconfig Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 12 18:07:02 volumio2 vtcs[6048]: STARTING TidalConnect services, version: 1.5.2.56 Dec 12 18:07:02 volumio2 vtcs[6048]: STARTED TidalConnect services. Dec 12 18:07:02 volumio2 volumio[1648]: info: Executing endpoint tc_connect Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 12 18:07:02 volumio2 volumio[1648]: info: Connecting to TidalConnect Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::servicePushState Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:02 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:02 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:02 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:02 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::servicePushState Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:02 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:02 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:02 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:02 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:02 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:02 volumio2 volumio[1648]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 12 18:07:02 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:02 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:02 volumio2 sudo[6064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:02 volumio2 sudo[6064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:02 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:02 volumio2 sudo[6066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:02 volumio2 sudo[6066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:02 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:02 volumio2 volumio[1648]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:02 volumio2 volumio[1648]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:02 volumio2 sudo[6064]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:02 volumio2 sudo[6079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 18:07:02 volumio2 sudo[6079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:02 volumio2 volumio[1648]: info: peppyspectrum Daemon Stop Dec 12 18:07:02 volumio2 sudo[6066]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:02 volumio2 volumio[1648]: info: peppyspectrum Daemon Stop Dec 12 18:07:02 volumio2 sudo[6079]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:02 volumio2 sudo[6083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 18:07:02 volumio2 sudo[6083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:02 volumio2 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 18:07:02 volumio2 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 18:07:02 volumio2 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 18:07:02 volumio2 sudo[6083]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:02 volumio2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 18:07:02 volumio2 mpd_monitor.sh[6085]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 18:07:02 volumio2 volumio[1648]: info: Successfully started MPD Monitor Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreStateMachine::getcurrentVolume Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:03 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:03 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:03 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:03 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreStateMachine::updateTrackBlock Dec 12 18:07:03 volumio2 volumio[1648]: info: CorePlayQueue::getTrackBlock Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 18:07:03 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:03 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:03 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:03 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:03 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:03 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:03 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:03 volumio2 sudo[6102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:03 volumio2 sudo[6102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:03 volumio2 sudo[6104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:03 volumio2 sudo[6104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:03 volumio2 sudo[6102]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:03 volumio2 volumio[1648]: info: peppyspectrum Daemon Stop Dec 12 18:07:03 volumio2 sudo[6104]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:03 volumio2 volumio[1648]: info: peppyspectrum Daemon Stop Dec 12 18:07:05 volumio2 volumio[1648]: info: TidalConnect service started! Dec 12 18:07:05 volumio2 volumio[1648]: info: TidalConnect service started! Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 18:07:06 volumio2 volumio[1648]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 12 18:07:06 volumio2 volumio[1648]: info: Received Get System Version Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 18:07:06 volumio2 volumio[1648]: info: Received Get System Info Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 18:07:06 volumio2 volumio[1648]: info: Discovery: Getting this device information Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:06 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:06 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:08 volumio2 volumio[1648]: info: Retrieving Cloud Streaming UI Dec 12 18:07:08 volumio2 volumio[1648]: info: Getting Tidal Cloud Configuration Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:08 volumio2 volumio[1648]: info: Getting Qobuz Cloud Configuration Dec 12 18:07:08 volumio2 volumio[1648]: info: Asking plugin for UI Config Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:08 volumio2 volumio[1648]: info: Getting Spotify Cloud Configuration Dec 12 18:07:08 volumio2 volumio[1648]: info: Asking plugin for UI Config Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:08 volumio2 volumio[1648]: info: Saving Spotify Acccount Dec 12 18:07:08 volumio2 volumio[1648]: info: Got it Dec 12 18:07:08 volumio2 volumio[1648]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 12 18:07:08 volumio2 volumio[1648]: info: Got it Dec 12 18:07:08 volumio2 volumio[1648]: info: Got Tidal Cloud Configuration Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 12 18:07:08 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: RRM: Ignoring radio measurement request: Not RRM network Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: wlan0: WNM: Preferred List Available Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: wlan0: Trying to associate with d4:da:21:79:ec:10 (SSID='SUVODOL WIFI' freq=5180 MHz) Dec 12 18:07:11 volumio2 dhcpcd[1027]: wlan0: carrier lost - roaming Dec 12 18:07:11 volumio2 dhcpcd[1027]: wlan0: changing route to 192.168.31.0/24 Dec 12 18:07:11 volumio2 dhcpcd[1027]: wlan0: changing default route via 192.168.31.1 Dec 12 18:07:11 volumio2 kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: wlan0: Associated with d4:da:21:79:ec:10 Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: wlan0: WPA: Key negotiation completed with d4:da:21:79:ec:10 [PTK=CCMP GTK=CCMP] Dec 12 18:07:11 volumio2 wpa_supplicant[2732]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:da:21:79:ec:10 completed [id=0 id_str=] Dec 12 18:07:11 volumio2 dhcpcd[1027]: wlan0: carrier acquired Dec 12 18:07:11 volumio2 dhcpcd[1027]: wlan0: IAID 67:ad:05:23 Dec 12 18:07:12 volumio2 dhcpcd[1027]: wlan0: soliciting an IPv6 router Dec 12 18:07:12 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:12 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:13 volumio2 ntpd[1149]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 12 18:07:13 volumio2 dhcpcd[1027]: wlan0: rebinding lease of 192.168.31.92 Dec 12 18:07:13 volumio2 dhcpcd[1027]: wlan0: leased 192.168.31.92 for 43200 seconds Dec 12 18:07:13 volumio2 dhcpcd[1027]: wlan0: changing route to 192.168.31.0/24 Dec 12 18:07:13 volumio2 dhcpcd[1027]: wlan0: changing default route via 192.168.31.1 Dec 12 18:07:13 volumio2 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:07:13 volumio2 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 12 18:07:13 volumio2 systemd[1]: welcome.service: Deactivated successfully. Dec 12 18:07:13 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 12 18:07:13 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 12 18:07:13 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 12 18:07:13 volumio2 welcome[6152]: Resolved ip:[1] 192.168.31.92 Dec 12 18:07:13 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 12 18:07:13 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 18:07:14 volumio2 volumio[1648]: info: Executing endpoint metavolumio Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 18:07:14 volumio2 volumio[1648]: info: Executing endpoint metavolumio Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 18:07:14 volumio2 volumio[1648]: info: Executing endpoint metavolumio Dec 12 18:07:14 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 18:07:15 volumio2 nmbd[1208]: [2025/12/12 18:07:15.849495, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 12 18:07:15 volumio2 nmbd[1208]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.92 for name WORKGROUP<1d>. Dec 12 18:07:15 volumio2 nmbd[1208]: This response was from IP 192.168.31.20, reporting an IP address of 192.168.31.20. Dec 12 18:07:21 volumio2 volumio[1648]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 sudo[6187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 18:07:21 volumio2 sudo[6188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 18:07:21 volumio2 sudo[6188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:21 volumio2 sudo[6187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:21 volumio2 sudo[6187]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:07:21 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:21 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 18:07:21 volumio2 volumio[1648]: info: MPD Permissions set Dec 12 18:07:21 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 12 18:07:21 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 18:07:21 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 12 18:07:21 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 18:07:21 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 18:07:21 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 18:07:21 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 18:07:21 volumio2 sudo[6197]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 18:07:21 volumio2 sudo[6197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:07:21 volumio2 sudo[6197]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:21 volumio2 mpd[6199]: 2025-12-12T18:07:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 18:07:21 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 18:07:21 volumio2 sudo[6188]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:21 volumio2 volumio[1648]: error: updateQueue error: null Dec 12 18:07:24 volumio2 sudo[6206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 18:07:24 volumio2 sudo[6206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:24 volumio2 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 18:07:24 volumio2 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 18:07:24 volumio2 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 18:07:24 volumio2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 18:07:24 volumio2 mpd_monitor.sh[6208]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 18:07:24 volumio2 sudo[6206]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:24 volumio2 volumio[1648]: info: Successfully started MPD Monitor Dec 12 18:07:43 volumio2 kernel: usb 1-1: USB disconnect, device number 5 Dec 12 18:07:43 volumio2 volumio[1648]: info: Dec 12 18:07:43 volumio2 volumio[1648]: ---------------------------- USB Audio Device Detached Dec 12 18:07:43 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 12 18:07:43 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:07:43 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 12 18:07:51 volumio2 kernel: usb 1-1: new high-speed USB device number 6 using xhci-hcd Dec 12 18:07:52 volumio2 kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 6.f2 Dec 12 18:07:52 volumio2 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 12 18:07:52 volumio2 kernel: usb 1-1: Product: SMSL Sanskrit 10th Dec 12 18:07:52 volumio2 kernel: usb 1-1: Manufacturer: SMSL Dec 12 18:07:53 volumio2 (udev-worker)[6267]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 12 18:07:53 volumio2 volumio[1648]: info: Dec 12 18:07:53 volumio2 volumio[1648]: ---------------------------- USB Audio Device Attached Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::Close All Modals sent Dec 12 18:07:53 volumio2 volumio[1648]: info: Preparing to save Alsa Options, stopping services first Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:53 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPause Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreStateMachine::pause Dec 12 18:07:53 volumio2 volumio[1648]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL Sanskrit 10th","alsacard":"S10th"},"i2s":false} Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:07:53 volumio2 volumio[1648]: info: Setting default mixerSoftMaster for Softvolume device Dec 12 18:07:53 volumio2 volumio[1648]: info: QobuzConnect: setDeactiveState invoked Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:53 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:53 volumio2 vtcs[6048]: [2025-12-12 18:07:53.682] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 12 18:07:53 volumio2 vtcs[6048]: [2025-12-12 18:07:53.682] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 12 18:07:53 volumio2 sudo[6286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:07:53 volumio2 sudo[6286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 12 18:07:53 volumio2 volumio[1648]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 12 18:07:53 volumio2 volumio[1648]: info: Disabling external Volume Control Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 18:07:53 volumio2 volumio[1648]: info: Enable softmixer device for audio device undefined Dec 12 18:07:53 volumio2 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 12 18:07:53 volumio2 systemd[1]: vtcs.service: Killing process 6055 (vtcs) with signal SIGKILL. Dec 12 18:07:53 volumio2 systemd[1]: vtcs.service: Deactivated successfully. Dec 12 18:07:53 volumio2 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 12 18:07:53 volumio2 sudo[6286]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 sudo[6299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:07:53 volumio2 sudo[6299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:53 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:53 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:53 volumio2 sudo[6299]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:53 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:53 volumio2 volumio[1648]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Dec 12 18:07:53 volumio2 sudo[6307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 18:07:53 volumio2 sudo[6307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:53 volumio2 volumio[1648]: info: Software Volume ALSA configuration written Dec 12 18:07:53 volumio2 volumio[1648]: info: Preparing to generate the ALSA configuration file Dec 12 18:07:53 volumio2 sudo[6307]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 volumio[1648]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 12 18:07:53 volumio2 volumio[1648]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 12 18:07:53 volumio2 volumio[1648]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 12 18:07:53 volumio2 sudo[6311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:53 volumio2 sudo[6311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: Reading ALSA contributions from plugins. Dec 12 18:07:53 volumio2 sudo[6313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 18:07:53 volumio2 sudo[6313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 18:07:53 volumio2 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 12 18:07:53 volumio2 qobuz-connect[6030]: 20251212 18:07:53.792 [6030.6030] INFO SampleApp: Stopping Local configuration server Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 18:07:53 volumio2 volumio[1648]: info: Asound.conf file unchanged, so no further update is needed Dec 12 18:07:53 volumio2 volumio[1648]: info: Output device has changed, restarting MPD Dec 12 18:07:53 volumio2 sudo[6311]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 volumio[1648]: info: Output device has changed, restarting Shairport Sync Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:07:53 volumio2 sudo[6324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 18:07:53 volumio2 sudo[6324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 sudo[6324]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 sudo[6326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 18:07:53 volumio2 sudo[6326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 18:07:53 volumio2 volumio[1648]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: QobuzConnect: setDeactiveState invoked Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:53 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:53 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 18:07:53 volumio2 sudo[6338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:07:53 volumio2 sudo[6338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 sudo[6336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 18:07:53 volumio2 sudo[6336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 12 18:07:53 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 18:07:53 volumio2 volumio[1648]: info: MPD Permissions set Dec 12 18:07:53 volumio2 volumio[1648]: info: peppyspectrum Daemon Stop Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 sudo[6346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 18:07:53 volumio2 sudo[6346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 18:07:53 volumio2 volumio[1648]: info: Starting Shairport Sync Dec 12 18:07:53 volumio2 sudo[6338]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 sudo[6346]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 sudo[6354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 18:07:53 volumio2 sudo[6354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 12 18:07:53 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 18:07:53 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 18:07:53 volumio2 sudo[6357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 18:07:53 volumio2 sudo[6357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:53 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 18:07:53 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 18:07:53 volumio2 sudo[6336]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 18:07:53 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 18:07:53 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 18:07:53 volumio2 systemd[1]: shairport-sync.service: Consumed 1.569s CPU time. Dec 12 18:07:53 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 18:07:53 volumio2 sudo[6354]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:53 volumio2 volumio[1648]: info: Shairport-Sync Started Dec 12 18:07:53 volumio2 sudo[6360]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 18:07:53 volumio2 sudo[6360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:07:53 volumio2 sudo[6360]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:54 volumio2 qobuz-connect[6030]: 20251212 18:07:54.135 [6030.6030] INFO SampleApp: shat down connection on UNIX socket Dec 12 18:07:54 volumio2 volumio[1648]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 12 18:07:54 volumio2 volumio[1648]: info: QobuzConnect: setDeactiveState invoked Dec 12 18:07:54 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:54 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:54 volumio2 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 12 18:07:54 volumio2 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 18:07:54 volumio2 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 18:07:54 volumio2 sudo[6313]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:54 volumio2 sudo[6357]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:54 volumio2 volumio[1648]: info: Executing endpoint qc_getconfig Dec 12 18:07:54 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.186 [6379.6379] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 12 18:07:54 volumio2 volumio[1648]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 12 18:07:54 volumio2 volumio[1648]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.188 [6379.6379] INFO VolumeManager: [0x11c4168]: Setting new playback volume: 75 Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.188 [6379.6379] INFO VolumeManager: [0x11c4168]: Setting new mute state: 0 Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.188 [6379.6379] INFO QobuzConnect: [0x11c4b38]: Client initialized! Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.188 [6379.6379] INFO SampleApp: Starting Avahi advertising, name: Volumio2, service name: _qobuz-connect._tcp Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.196 [6379.6379] INFO LocalConfigManager: [0x11c3a48]: Starting Local Configuration server Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.196 [6379.6379] INFO SampleApp: Starting Local configuration server Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.196 [6379.6379] INFO SampleApp: Connected to UNIX socket client 0x11ae818 Dec 12 18:07:54 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 12 18:07:54 volumio2 qobuz-connect[6379]: 20251212 18:07:54.336 [6379.6379] INFO SampleApp: Playback volume changed: 75 Dec 12 18:07:54 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:54 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:54 volumio2 mpd[6376]: 2025-12-12T18:07:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 18:07:54 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 18:07:54 volumio2 sudo[6326]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:54 volumio2 volumio[1648]: error: updateQueue error: null Dec 12 18:07:56 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:56 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:56 volumio2 volumio[1648]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:56 volumio2 volumio[1648]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:56 volumio2 sudo[6396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 18:07:56 volumio2 sudo[6396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:56 volumio2 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 12 18:07:56 volumio2 sudo[6396]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:56 volumio2 volumio[1648]: info: Executing endpoint tc_getconfig Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 12 18:07:56 volumio2 vtcs[6399]: STARTING TidalConnect services, version: 1.5.2.56 Dec 12 18:07:56 volumio2 volumio[1648]: info: TidalConnect service stoped! Dec 12 18:07:56 volumio2 vtcs[6399]: STARTED TidalConnect services. Dec 12 18:07:56 volumio2 volumio[1648]: info: Executing endpoint tc_connect Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 12 18:07:56 volumio2 volumio[1648]: info: Connecting to TidalConnect Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::servicePushState Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:56 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:56 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:56 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:56 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:56 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:56 volumio2 volumio[1648]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::servicePushState Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreStateMachine::pushState Dec 12 18:07:56 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::volumioPushState Dec 12 18:07:56 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output update for this device Dec 12 18:07:56 volumio2 volumio[1648]: info: MRS: Pushing multiroomSync output Dec 12 18:07:56 volumio2 volumio[1648]: info: CoreCommandRouter::volumioGetState Dec 12 18:07:56 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:56 volumio2 volumio[1648]: info: CorePlayQueue::getTrack 10 Dec 12 18:07:56 volumio2 volumio[1648]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 12 18:07:56 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:56 volumio2 volumio[1648]: info: PeppySpectrum ---peppyspectrum status stop Dec 12 18:07:56 volumio2 sudo[6421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:56 volumio2 sudo[6421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:56 volumio2 volumio[1648]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:56 volumio2 volumio[1648]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 18:07:56 volumio2 sudo[6425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 12 18:07:56 volumio2 sudo[6425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 18:07:56 volumio2 volumio[1648]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 18:07:56 volumio2 volumio[1648]: Error: ENOENT: no such file or directory, unlink '/tmp/cfFlE9jfBQWSUrInfM87lqY4Spo2vGPxFrH2iydRLGLdNe5H6QS2IQpASDzDuc5gSsdQig0AYNTNxNFPVk68jUMz2dyaD9fQRHW9' Dec 12 18:07:56 volumio2 volumio[1648]: at Object.unlinkSync (node:fs:1899:3) Dec 12 18:07:56 volumio2 volumio[1648]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Dec 12 18:07:56 volumio2 volumio[1648]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Dec 12 18:07:56 volumio2 volumio[1648]: at TidalConnectClient.emit (node:events:514:28) Dec 12 18:07:56 volumio2 volumio[1648]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Dec 12 18:07:56 volumio2 volumio[1648]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Dec 12 18:07:56 volumio2 volumio[1648]: at Array.forEach () Dec 12 18:07:56 volumio2 volumio[1648]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Dec 12 18:07:56 volumio2 volumio[1648]: at Socket.emit (node:events:514:28) Dec 12 18:07:56 volumio2 volumio[1648]: at addChunk (node:internal/streams/readable:343:12) Dec 12 18:07:56 volumio2 volumio[1648]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 12 18:07:56 volumio2 volumio[1648]: at Readable.push (node:internal/streams/readable:253:10) Dec 12 18:07:56 volumio2 volumio[1648]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Dec 12 18:07:56 volumio2 volumio[1648]: errno: -2, Dec 12 18:07:56 volumio2 volumio[1648]: syscall: 'unlink', Dec 12 18:07:56 volumio2 volumio[1648]: code: 'ENOENT', Dec 12 18:07:56 volumio2 volumio[1648]: path: '/tmp/cfFlE9jfBQWSUrInfM87lqY4Spo2vGPxFrH2iydRLGLdNe5H6QS2IQpASDzDuc5gSsdQig0AYNTNxNFPVk68jUMz2dyaD9fQRHW9' Dec 12 18:07:56 volumio2 volumio[1648]: } Dec 12 18:07:56 volumio2 volumio[1648]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 18:07:56 volumio2 sudo[6425]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:56 volumio2 sudo[6421]: pam_unix(sudo:session): session closed for user root Dec 12 18:07:57 volumio2 sudo[6443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 18:06' Dec 12 18:07:57 volumio2 sudo[6443]: 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"