Dec 17 21:34:01 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:01 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:01 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:01 volumiohp kernel: usb 2-1: USB disconnect, device number 8 Dec 17 21:34:01 volumiohp volumio[1065]: info: Dec 17 21:34:01 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:01 volumiohp kernel: usb 2-1: new high-speed USB device number 9 using ehci-pci Dec 17 21:34:01 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:01 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:01 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:01 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:01 volumiohp (udev-worker)[3436]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:01 volumiohp volumio[1065]: info: Dec 17 21:34:01 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 17 21:34:02 volumiohp volumio[1065]: info: Preparing to save Alsa Options, stopping services first Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:02 volumiohp volumio[1065]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1,0","label":"Analog Out"},"disallowPush":true} Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:02 volumiohp volumio[1065]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 17 21:34:02 volumiohp vtcs[3376]: [2025-12-17 20:34:02.628] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 17 21:34:02 volumiohp vtcs[3376]: [2025-12-17 20:34:02.632] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 17 21:34:02 volumiohp sudo[3456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:02 volumiohp sudo[3456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp volumio[1065]: info: Setting mixer Master for card Analog Out Dec 17 21:34:02 volumiohp systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 17 21:34:02 volumiohp systemd[1]: vtcs.service: Killing process 3394 (vtcs) with signal SIGKILL. Dec 17 21:34:02 volumiohp systemd[1]: vtcs.service: Deactivated successfully. Dec 17 21:34:02 volumiohp systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 17 21:34:02 volumiohp sudo[3461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:02 volumiohp sudo[3461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 17 21:34:02 volumiohp volumio[1065]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 17 21:34:02 volumiohp volumio[1065]: info: Disabling external Volume Control Dec 17 21:34:02 volumiohp sudo[3456]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:02 volumiohp volumio[1065]: info: Preparing to generate the ALSA configuration file Dec 17 21:34:02 volumiohp sudo[3461]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:02 volumiohp volumio[1065]: info: Retrieving Cloud Streaming UI Dec 17 21:34:02 volumiohp sudo[3471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:02 volumiohp sudo[3472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:02 volumiohp sudo[3472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp sudo[3471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:02 volumiohp sudo[3472]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:02 volumiohp sudo[3471]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 21:34:02 volumiohp volumio[1065]: info: Getting Tidal Cloud Configuration Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:02 volumiohp volumio[1065]: info: Getting Qobuz Cloud Configuration Dec 17 21:34:02 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:02 volumiohp volumio[1065]: info: Getting Spotify Cloud Configuration Dec 17 21:34:02 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:02 volumiohp volumio[1065]: info: Saving Spotify Acccount Dec 17 21:34:02 volumiohp volumio[1065]: info: Got it Dec 17 21:34:02 volumiohp volumio[1065]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 17 21:34:02 volumiohp volumio[1065]: info: VolumeController:: Volume=41 Mute =false Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:02 volumiohp volumio[1065]: info: Asound.conf file written Dec 17 21:34:02 volumiohp sudo[3488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 17 21:34:02 volumiohp sudo[3488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp sudo[3488]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:02 volumiohp volumio[1065]: No state is present for card HDMI Dec 17 21:34:02 volumiohp volumio[1065]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100500" "0x103c" "0x21b4" Dec 17 21:34:02 volumiohp volumio[1065]: Hardware is initialized using a generic method Dec 17 21:34:02 volumiohp volumio[1065]: No state is present for card HDMI Dec 17 21:34:02 volumiohp volumio[1065]: No state is present for card Generic Dec 17 21:34:02 volumiohp volumio[1065]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0221,103c21b4,00100003" "0x103c" "0x21b4" Dec 17 21:34:02 volumiohp volumio[1065]: Hardware is initialized using a generic method Dec 17 21:34:02 volumiohp volumio[1065]: No state is present for card Generic Dec 17 21:34:02 volumiohp volumio[1065]: No state is present for card x20 Dec 17 21:34:02 volumiohp volumio[1065]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Dec 17 21:34:02 volumiohp volumio[1065]: Hardware is initialized using a generic method Dec 17 21:34:02 volumiohp volumio[1065]: No state is present for card x20 Dec 17 21:34:02 volumiohp volumio[1065]: info: Output device has changed, restarting MPD Dec 17 21:34:02 volumiohp volumio[1065]: info: Output device has changed, restarting Shairport Sync Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:02 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:34:02 volumiohp sudo[3494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 21:34:02 volumiohp sudo[3494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp sudo[3494]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:02 volumiohp sudo[3496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 21:34:02 volumiohp sudo[3496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:02 volumiohp volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 21:34:02 volumiohp volumio[1065]: info: Output device has changed, restarting MPD Dec 17 21:34:03 volumiohp systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 17 21:34:03 volumiohp sudo[3504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:03 volumiohp volumio[1065]: info: Output device has changed, restarting Shairport Sync Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:34:03 volumiohp sudo[3504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp sudo[3507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 21:34:03 volumiohp systemd[1]: mpd.service: Deactivated successfully. Dec 17 21:34:03 volumiohp sudo[3507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 21:34:03 volumiohp systemd[1]: mpd.service: Consumed 1.359s CPU time. Dec 17 21:34:03 volumiohp volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 21:34:03 volumiohp systemd[1]: mpd.socket: Deactivated successfully. Dec 17 21:34:03 volumiohp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 21:34:03 volumiohp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 21:34:03 volumiohp sudo[3507]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp sudo[3509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 21:34:03 volumiohp sudo[3509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp sudo[3517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:03 volumiohp sudo[3517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp sudo[3521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:03 volumiohp sudo[3521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp volumio[1065]: info: MPD Permissions set Dec 17 21:34:03 volumiohp volumio[1065]: info: MPD Permissions set Dec 17 21:34:03 volumiohp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 21:34:03 volumiohp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 21:34:03 volumiohp volumio[1065]: info: Got Tidal Cloud Configuration Dec 17 21:34:03 volumiohp sudo[3523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:34:03 volumiohp sudo[3523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp volumio[1065]: info: Got it Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:03 volumiohp volumio[1065]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp systemd[1]: mpd.service: Deactivated successfully. Dec 17 21:34:03 volumiohp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp sudo[3504]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 21:34:03 volumiohp sudo[3521]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:03 volumiohp sudo[3517]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp systemd[1]: mpd.socket: Deactivated successfully. Dec 17 21:34:03 volumiohp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 21:34:03 volumiohp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 21:34:03 volumiohp volumio[1065]: info: Starting Shairport Sync Dec 17 21:34:03 volumiohp volumio[1065]: info: Starting Shairport Sync Dec 17 21:34:03 volumiohp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 21:34:03 volumiohp sudo[3549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 21:34:03 volumiohp sudo[3549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 21:34:03 volumiohp sudo[3523]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 21:34:03 volumiohp systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 21:34:03 volumiohp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:34:03 volumiohp systemd[1]: shairport-sync.service: Consumed 1.636s CPU time. Dec 17 21:34:03 volumiohp sudo[3555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 21:34:03 volumiohp sudo[3555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:03 volumiohp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:34:03 volumiohp sudo[3549]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp volumio[1065]: info: Shairport-Sync Started Dec 17 21:34:03 volumiohp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 21:34:03 volumiohp systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 21:34:03 volumiohp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:34:03 volumiohp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:34:03 volumiohp sudo[3555]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:03 volumiohp volumio[1065]: info: Shairport-Sync Started Dec 17 21:34:03 volumiohp sudo[3553]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 17 21:34:03 volumiohp sudo[3553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 21:34:03 volumiohp sudo[3553]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:04 volumiohp kernel: usb 2-1: USB disconnect, device number 9 Dec 17 21:34:04 volumiohp volumio[1065]: info: Dec 17 21:34:04 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:04 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:04 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:04 volumiohp kernel: usb 2-1: new high-speed USB device number 10 using ehci-pci Dec 17 21:34:04 volumiohp mpd[3561]: 2025-12-17T20:34:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 21:34:04 volumiohp systemd[1]: Started mpd.service - Music Player Daemon. Dec 17 21:34:04 volumiohp sudo[3496]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:04 volumiohp sudo[3509]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:04 volumiohp volumio[1065]: error: MPD error: The expression evaluated to a falsy value: Dec 17 21:34:04 volumiohp volumio[1065]: assert.ok(self.idling) Dec 17 21:34:04 volumiohp volumio[1065]: error: The expression evaluated to a falsy value: Dec 17 21:34:04 volumiohp volumio[1065]: assert.ok(self.idling) Dec 17 21:34:04 volumiohp volumio[1065]: error: updateQueue error: null Dec 17 21:34:04 volumiohp kernel: usb 2-1: device not accepting address 10, error -71 Dec 17 21:34:05 volumiohp kernel: usb 2-1: new high-speed USB device number 11 using ehci-pci Dec 17 21:34:05 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:05 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:05 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:05 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:05 volumiohp (udev-worker)[3436]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:05 volumiohp volumio[1065]: info: Dec 17 21:34:05 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:05 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:05 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:05 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:05 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:05 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:05 volumiohp volumio[1065]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:05 volumiohp volumio[1065]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:05 volumiohp volumio[1065]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:05 volumiohp volumio[1065]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:05 volumiohp sudo[3606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 17 21:34:05 volumiohp sudo[3606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:05 volumiohp sudo[3608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 17 21:34:05 volumiohp sudo[3608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:05 volumiohp systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 17 21:34:05 volumiohp sudo[3606]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:05 volumiohp sudo[3608]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:06 volumiohp volumio[1065]: info: Executing endpoint tc_getconfig Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 17 21:34:06 volumiohp vtcs[3613]: STARTING TidalConnect services, version: 1.5.2.56 Dec 17 21:34:06 volumiohp vtcs[3613]: STARTED TidalConnect services. Dec 17 21:34:06 volumiohp volumio[1065]: info: Executing endpoint tc_connect Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 17 21:34:06 volumiohp volumio[1065]: info: Connecting to TidalConnect Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::servicePushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CorePlayQueue::getTrack 0 Dec 17 21:34:06 volumiohp volumio[1065]: 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 17 21:34:06 volumiohp volumio[1065]: verbose: CURRENT POSITION 0 Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::syncState stateService stop Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: info: No code Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::servicePushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CorePlayQueue::getTrack 0 Dec 17 21:34:06 volumiohp volumio[1065]: 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 17 21:34:06 volumiohp volumio[1065]: verbose: CURRENT POSITION 0 Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::syncState stateService stop Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: info: No code Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp kernel: usb 2-1: USB disconnect, device number 11 Dec 17 21:34:06 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:06 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:06 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:06 volumiohp volumio[1065]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:06 volumiohp volumio[1065]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:06 volumiohp volumio[1065]: info: Dec 17 21:34:06 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:06 volumiohp sudo[3642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 17 21:34:06 volumiohp sudo[3642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:06 volumiohp sudo[3642]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:06 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:34:06 volumiohp volumio[1065]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:06 volumiohp volumio[1065]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:34:06 volumiohp sudo[3657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 17 21:34:06 volumiohp sudo[3657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:06 volumiohp sudo[3657]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:06 volumiohp kernel: usb 2-1: new high-speed USB device number 12 using ehci-pci Dec 17 21:34:06 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:06 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:06 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:06 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:06 volumiohp (udev-worker)[3437]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:06 volumiohp kernel: usb 2-1: USB disconnect, device number 12 Dec 17 21:34:06 volumiohp volumio[1065]: info: Dec 17 21:34:06 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::getcurrentVolume Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 21:34:06 volumiohp volumio[1065]: info: VolumeController:: Volume=41 Mute =false Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::updateTrackBlock Dec 17 21:34:06 volumiohp volumio[1065]: info: CorePlayQueue::getTrackBlock Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp volumio[1065]: info: VolumeController:: Volume=41 Mute =false Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:34:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:34:06 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:34:06 volumiohp kernel: usb 2-1: new high-speed USB device number 13 using ehci-pci Dec 17 21:34:07 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:07 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:07 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:07 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:07 volumiohp volumio[1065]: info: Dec 17 21:34:07 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:07 volumiohp (udev-worker)[3437]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:07 volumiohp kernel: usb 2-1: USB disconnect, device number 13 Dec 17 21:34:07 volumiohp volumio[1065]: info: Dec 17 21:34:07 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:07 volumiohp kernel: usb 2-1: new high-speed USB device number 14 using ehci-pci Dec 17 21:34:07 volumiohp sudo[3677]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 21:34:07 volumiohp sudo[3677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:07 volumiohp sudo[3677]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:07 volumiohp sudo[3679]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 21:34:07 volumiohp sudo[3679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:07 volumiohp sudo[3682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 21:34:07 volumiohp sudo[3682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:07 volumiohp sudo[3685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 21:34:07 volumiohp sudo[3685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:07 volumiohp sudo[3679]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:07 volumiohp systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 17 21:34:07 volumiohp systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 17 21:34:07 volumiohp systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 17 21:34:07 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:07 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:07 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:07 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:07 volumiohp systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 17 21:34:07 volumiohp sudo[3682]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:07 volumiohp mpd_monitor.sh[3690]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 21:34:07 volumiohp volumio[1065]: info: Successfully started MPD Monitor Dec 17 21:34:07 volumiohp systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 17 21:34:07 volumiohp systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 17 21:34:07 volumiohp systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 17 21:34:07 volumiohp systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 17 21:34:07 volumiohp sudo[3685]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:07 volumiohp mpd_monitor.sh[3692]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 21:34:07 volumiohp volumio[1065]: info: Successfully started MPD Monitor Dec 17 21:34:07 volumiohp volumio[1065]: info: Dec 17 21:34:07 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:07 volumiohp (udev-worker)[3437]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:07 volumiohp volumio[1065]: info: Dec 17 21:34:07 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:08 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:08 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:09 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:09 volumiohp volumio[1065]: info: TidalConnect service started! Dec 17 21:34:11 volumiohp kernel: usb 2-1: USB disconnect, device number 14 Dec 17 21:34:11 volumiohp volumio[1065]: info: Dec 17 21:34:11 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:11 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:11 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:11 volumiohp kernel: usb 2-1: new high-speed USB device number 15 using ehci-pci Dec 17 21:34:11 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:11 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:11 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:11 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:11 volumiohp (udev-worker)[3722]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:11 volumiohp volumio[1065]: info: Dec 17 21:34:11 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:11 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:14 volumiohp kernel: usb 2-1: USB disconnect, device number 15 Dec 17 21:34:14 volumiohp volumio[1065]: info: Dec 17 21:34:14 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:14 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:14 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:14 volumiohp kernel: usb 2-1: new high-speed USB device number 16 using ehci-pci Dec 17 21:34:14 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:14 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:14 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:14 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:14 volumiohp (udev-worker)[3722]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:14 volumiohp volumio[1065]: info: Dec 17 21:34:14 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:14 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:16 volumiohp kernel: usb 2-1: USB disconnect, device number 16 Dec 17 21:34:16 volumiohp volumio[1065]: info: Dec 17 21:34:16 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:16 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:16 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:16 volumiohp kernel: usb 2-1: new high-speed USB device number 17 using ehci-pci Dec 17 21:34:16 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:16 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:16 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:16 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:16 volumiohp (udev-worker)[3722]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:16 volumiohp volumio[1065]: info: Dec 17 21:34:16 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:16 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:22 volumiohp kernel: usb 2-1: USB disconnect, device number 17 Dec 17 21:34:22 volumiohp volumio[1065]: info: Dec 17 21:34:22 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:22 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:22 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:22 volumiohp kernel: usb 2-1: new high-speed USB device number 18 using ehci-pci Dec 17 21:34:22 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:22 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:22 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:22 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:22 volumiohp (udev-worker)[3779]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:22 volumiohp volumio[1065]: info: Dec 17 21:34:22 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:22 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:22 volumiohp kernel: usb 2-1: USB disconnect, device number 18 Dec 17 21:34:22 volumiohp volumio[1065]: info: Dec 17 21:34:22 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:22 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:22 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:23 volumiohp kernel: usb 2-1: new high-speed USB device number 19 using ehci-pci Dec 17 21:34:23 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:23 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:23 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:23 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:23 volumiohp (udev-worker)[3777]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:23 volumiohp volumio[1065]: info: Dec 17 21:34:23 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:23 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:27 volumiohp kernel: usb 2-1: USB disconnect, device number 19 Dec 17 21:34:27 volumiohp volumio[1065]: info: Dec 17 21:34:27 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:27 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:27 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:27 volumiohp kernel: usb 2-1: new high-speed USB device number 20 using ehci-pci Dec 17 21:34:27 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:27 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:27 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:27 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:27 volumiohp (udev-worker)[3804]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:27 volumiohp volumio[1065]: info: Dec 17 21:34:27 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:27 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:27 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 17 21:34:28 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 17 21:34:29 volumiohp kernel: usb 2-1: USB disconnect, device number 20 Dec 17 21:34:29 volumiohp volumio[1065]: info: Dec 17 21:34:29 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:29 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:29 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:29 volumiohp kernel: usb 2-1: new high-speed USB device number 21 using ehci-pci Dec 17 21:34:29 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:29 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:29 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:29 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:29 volumiohp (udev-worker)[3838]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:29 volumiohp volumio[1065]: info: Dec 17 21:34:29 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:29 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:30 volumiohp kernel: usb 2-1: USB disconnect, device number 21 Dec 17 21:34:30 volumiohp volumio[1065]: info: Dec 17 21:34:30 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:30 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:30 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:30 volumiohp kernel: usb 2-1: new high-speed USB device number 22 using ehci-pci Dec 17 21:34:30 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:30 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:30 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:30 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:30 volumiohp (udev-worker)[3838]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:30 volumiohp kernel: usb 2-1: USB disconnect, device number 22 Dec 17 21:34:30 volumiohp volumio[1065]: info: Dec 17 21:34:30 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:30 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:30 volumiohp kernel: usb 2-1: new high-speed USB device number 23 using ehci-pci Dec 17 21:34:31 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:31 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:31 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:31 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:31 volumiohp volumio[1065]: info: Dec 17 21:34:31 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:31 volumiohp (udev-worker)[3804]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:31 volumiohp volumio[1065]: info: Dec 17 21:34:31 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:31 volumiohp volumio[1065]: info: Received OAUTH Data Dec 17 21:34:31 volumiohp volumio[1065]: info: Refreshing TIDAL token Dec 17 21:34:31 volumiohp volumio[1065]: info: TIDAL Access Token successfully retrieved Dec 17 21:34:31 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:31 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 21:34:32 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:32 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:32 volumiohp volumio[1065]: info: Listing playlists Dec 17 21:34:32 volumiohp volumio[1065]: info: Successfully retrieved User Session From TIDAL Dec 17 21:34:32 volumiohp volumio[1065]: info: Adding TIDAL to Browse Sources Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 21:34:32 volumiohp volumio[1065]: info: [1766003672018] CoreMusicLibrary::Adding element TIDAL Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:32 volumiohp volumio[1065]: Cannot find translation for source TIDAL Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Retrieving Cloud Streaming UI Dec 17 21:34:32 volumiohp volumio[1065]: info: Getting Tidal Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Getting Qobuz Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Getting Spotify Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Saving Spotify Acccount Dec 17 21:34:32 volumiohp volumio[1065]: info: Got it Dec 17 21:34:32 volumiohp volumio[1065]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 17 21:34:32 volumiohp volumio[1065]: info: Got Tidal Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: Got it Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 21:34:32 volumiohp sudo[3868]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 21:34:32 volumiohp sudo[3867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 21:34:32 volumiohp sudo[3868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:32 volumiohp sudo[3867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:32 volumiohp sudo[3867]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:32 volumiohp sudo[3868]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:32 volumiohp volumio[1065]: info: Successfully retrieved User Subscription From TIDAL Dec 17 21:34:32 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 17 21:34:32 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:32 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:32 volumiohp volumio-remote-updater[812]: Test mode disabled Dec 17 21:34:32 volumiohp volumio-remote-updater[812]: Alpha mode disabled Dec 17 21:34:32 volumiohp volumio-remote-updater[812]: Alpha legacy test mode disabled Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Retrieving Cloud Streaming UI Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:34:32 volumiohp volumio[1065]: info: Getting Tidal Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Getting Qobuz Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Getting Spotify Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: info: Saving Spotify Acccount Dec 17 21:34:32 volumiohp volumio[1065]: info: Got it Dec 17 21:34:32 volumiohp volumio[1065]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 17 21:34:32 volumiohp kernel: usb 2-1: USB disconnect, device number 23 Dec 17 21:34:32 volumiohp volumio[1065]: info: Got Tidal Cloud Configuration Dec 17 21:34:32 volumiohp volumio[1065]: info: Got it Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:32 volumiohp volumio[1065]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 17 21:34:32 volumiohp volumio[1065]: info: Dec 17 21:34:32 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:32 volumiohp volumio[1065]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 17 21:34:32 volumiohp kernel: usb 2-1: new high-speed USB device number 24 using ehci-pci Dec 17 21:34:32 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:32 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:32 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:32 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:32 volumiohp (udev-worker)[3838]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:32 volumiohp volumio[1065]: info: Dec 17 21:34:32 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:32 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:33 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 17 21:34:33 volumiohp volumio[1065]: info: Loading i18n strings for locale de Dec 17 21:34:33 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 17 21:34:33 volumiohp volumio[1065]: info: Setting timezone to Europe/Amsterdam Dec 17 21:34:33 volumiohp sudo[3891]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 17 21:34:33 volumiohp sudo[3891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:33 volumiohp sudo[3891]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:33 volumiohp sudo[3895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime Dec 17 21:34:33 volumiohp sudo[3895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:33 volumiohp sudo[3895]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:33 volumiohp sudo[3899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 17 21:34:33 volumiohp sudo[3899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:33 volumiohp sudo[3899]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:33 volumiohp sudo[3903]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam Dec 17 21:34:33 volumiohp sudo[3903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:33 volumiohp dbus-daemon[795]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.55' (uid=0 pid=3904 comm="/usr/bin/timedatectl set-timezone Europe/Amsterdam") Dec 17 21:34:33 volumiohp systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 17 21:34:33 volumiohp dbus-daemon[795]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 17 21:34:33 volumiohp systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 17 21:34:33 volumiohp sudo[3903]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:33 volumiohp volumio[1065]: Updating browse sources language Dec 17 21:34:33 volumiohp volumio[1065]: Cannot find translation for source TIDAL Dec 17 21:34:33 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:33 volumiohp volumio[1065]: Cannot find translation for source TIDAL Dec 17 21:34:33 volumiohp volumio[1065]: info: Fetching Streaming Services browse cache Dec 17 21:34:34 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:34 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:36 volumiohp kernel: usb 2-1: USB disconnect, device number 24 Dec 17 21:34:36 volumiohp volumio[1065]: info: Dec 17 21:34:36 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:36 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:36 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:36 volumiohp kernel: usb 2-1: new high-speed USB device number 25 using ehci-pci Dec 17 21:34:37 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:37 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:37 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:37 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:37 volumiohp (udev-worker)[3915]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:37 volumiohp volumio[1065]: info: Dec 17 21:34:37 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:37 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:37 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 17 21:34:37 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 17 21:34:37 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 17 21:34:37 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 17 21:34:37 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::Reload Ui Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 17 21:34:42 volumiohp volumio[1065]: info: Wizard terminated Successfully Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::volumiosetStartupVolume Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:34:42 volumiohp sudo[3968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 21:34:42 volumiohp sudo[3966]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 21:34:42 volumiohp sudo[3968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:42 volumiohp sudo[3966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:42 volumiohp sudo[3966]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:42 volumiohp sudo[3968]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:42 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 17 21:34:42 volumiohp sudo[3975]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 21:34:42 volumiohp sudo[3975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:42 volumiohp sudo[3977]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 21:34:42 volumiohp sudo[3975]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:42 volumiohp sudo[3977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:42 volumiohp sudo[3977]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:42 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:42 volumiohp volumio[1065]: info: Retrieving Cloud Streaming UI Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 21:34:42 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:42 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:42 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: Listing playlists Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:34:43 volumiohp volumio[1065]: info: Getting Tidal Cloud Configuration Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:43 volumiohp volumio[1065]: info: Getting Qobuz Cloud Configuration Dec 17 21:34:43 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:43 volumiohp volumio[1065]: info: Getting Spotify Cloud Configuration Dec 17 21:34:43 volumiohp volumio[1065]: info: Asking plugin for UI Config Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:43 volumiohp volumio[1065]: info: Saving Spotify Acccount Dec 17 21:34:43 volumiohp volumio[1065]: info: Got it Dec 17 21:34:43 volumiohp volumio[1065]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 17 21:34:43 volumiohp volumio[1065]: info: Got Tidal Cloud Configuration Dec 17 21:34:43 volumiohp volumio[1065]: info: Got it Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:43 volumiohp volumio[1065]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 17 21:34:43 volumiohp kernel: usb 2-1: USB disconnect, device number 25 Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 21:34:43 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:43 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: Listing playlists Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:34:43 volumiohp volumio[1065]: info: Dec 17 21:34:43 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:43 volumiohp kernel: usb 2-1: new high-speed USB device number 26 using ehci-pci Dec 17 21:34:43 volumiohp sudo[3992]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 21:34:43 volumiohp sudo[3992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:43 volumiohp sudo[3994]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 21:34:43 volumiohp sudo[3994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:34:43 volumiohp sudo[3992]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 21:34:43 volumiohp sudo[3994]: pam_unix(sudo:session): session closed for user root Dec 17 21:34:43 volumiohp volumio[1065]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 17 21:34:43 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:43 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:43 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:43 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 21:34:43 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:43 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:43 volumiohp volumio[1065]: info: Listing playlists Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:34:43 volumiohp (udev-worker)[3985]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:43 volumiohp volumio[1065]: info: Dec 17 21:34:43 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:43 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 21:34:44 volumiohp kernel: usb 2-1: USB disconnect, device number 26 Dec 17 21:34:44 volumiohp volumio[1065]: info: Dec 17 21:34:44 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:44 volumiohp volumio[1065]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 17 21:34:44 volumiohp kernel: usb 2-1: new high-speed USB device number 27 using ehci-pci Dec 17 21:34:44 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:44 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:44 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:44 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:44 volumiohp (udev-worker)[3983]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:44 volumiohp volumio[1065]: info: Dec 17 21:34:44 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:44 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:44 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:44 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:45 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:45 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:45 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:45 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:45 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:45 volumiohp volumio[1065]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:46 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:46 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 21:34:46 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:46 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:46 volumiohp volumio[1065]: info: Listing playlists Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:34:46 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 17 21:34:47 volumiohp kernel: usb 2-1: USB disconnect, device number 27 Dec 17 21:34:47 volumiohp volumio[1065]: info: Dec 17 21:34:47 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:47 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:47 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:47 volumiohp kernel: usb 2-1: new high-speed USB device number 28 using ehci-pci Dec 17 21:34:47 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:47 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:47 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:47 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:47 volumiohp (udev-worker)[3983]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:47 volumiohp volumio[1065]: info: Dec 17 21:34:47 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:47 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:47 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:48 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:48 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 17 21:34:48 volumiohp kernel: usb 2-1: USB disconnect, device number 28 Dec 17 21:34:48 volumiohp volumio[1065]: info: Dec 17 21:34:48 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:48 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:49 volumiohp kernel: usb 2-1: new high-speed USB device number 29 using ehci-pci Dec 17 21:34:49 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:49 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:49 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:49 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:49 volumiohp (udev-worker)[3983]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:49 volumiohp volumio[1065]: info: Dec 17 21:34:49 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:49 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:51 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:51 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 21:34:51 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:34:51 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:34:51 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:34:52 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 21:34:52 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 17 21:34:52 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 17 21:34:53 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 17 21:34:53 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 21:34:53 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 21:34:54 volumiohp kernel: usb 2-1: USB disconnect, device number 29 Dec 17 21:34:54 volumiohp volumio[1065]: info: Dec 17 21:34:54 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:54 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:54 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:54 volumiohp kernel: usb 2-1: new high-speed USB device number 30 using ehci-pci Dec 17 21:34:54 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:54 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:54 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:54 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:55 volumiohp (udev-worker)[4063]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:55 volumiohp volumio[1065]: info: Dec 17 21:34:55 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:55 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:55 volumiohp kernel: usb 2-1: USB disconnect, device number 30 Dec 17 21:34:55 volumiohp volumio[1065]: info: Dec 17 21:34:55 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:34:55 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:34:55 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:55 volumiohp kernel: usb 2-1: new high-speed USB device number 31 using ehci-pci Dec 17 21:34:55 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:34:55 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:34:55 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:34:55 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:34:55 volumiohp (udev-worker)[4074]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:34:55 volumiohp volumio[1065]: info: Dec 17 21:34:55 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:34:55 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:34:55 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 21:34:55 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 21:34:59 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:35:01 volumiohp volumio[1065]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 17 21:35:01 volumiohp volumio[1065]: info: Received Get System Version Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 21:35:01 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:35:01 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:35:01 volumiohp volumio[1065]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 17 21:35:01 volumiohp volumio[1065]: info: Received Get System Version Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 21:35:01 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:35:01 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:35:01 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:35:03 volumiohp kernel: usb 2-1: USB disconnect, device number 31 Dec 17 21:35:03 volumiohp volumio[1065]: info: Dec 17 21:35:03 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:03 volumiohp kernel: usb 2-1: new high-speed USB device number 32 using ehci-pci Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 21:35:03 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:35:03 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:35:03 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:35:03 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:35:03 volumiohp systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 17 21:35:03 volumiohp (udev-worker)[4115]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:35:03 volumiohp volumio[1065]: info: Dec 17 21:35:03 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:35:03 volumiohp volumio-remote-updater[812]: Test mode disabled Dec 17 21:35:03 volumiohp volumio-remote-updater[812]: Alpha mode disabled Dec 17 21:35:03 volumiohp volumio-remote-updater[812]: Alpha legacy test mode disabled Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 21:35:03 volumiohp volumio[1065]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 17 21:35:03 volumiohp volumio[1065]: info: Received Get System Version Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 21:35:03 volumiohp volumio[1065]: info: Received Get System Info Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 21:35:03 volumiohp volumio[1065]: info: Discovery: Getting this device information Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 21:35:03 volumiohp volumio[1065]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 17 21:35:03 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 17 21:35:04 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 21:35:06 volumiohp volumio[1065]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 17 21:35:06 volumiohp volumio[1065]: info: Preparing to save Alsa Options, stopping services first Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:35:06 volumiohp volumio[1065]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"xCORE USB Audio 2.0"}} Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:06 volumiohp volumio[1065]: info: Device 5 does not have any Mixer Control Available Dec 17 21:35:06 volumiohp vtcs[3613]: [2025-12-17 21:35:06.738] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 17 21:35:06 volumiohp vtcs[3613]: [2025-12-17 21:35:06.738] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 17 21:35:06 volumiohp sudo[4145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:06 volumiohp sudo[4145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 17 21:35:06 volumiohp volumio[1065]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 17 21:35:06 volumiohp volumio[1065]: info: Disabling external Volume Control Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:35:06 volumiohp systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 17 21:35:06 volumiohp systemd[1]: vtcs.service: Deactivated successfully. Dec 17 21:35:06 volumiohp volumio[1065]: info: Preparing to generate the ALSA configuration file Dec 17 21:35:06 volumiohp systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 17 21:35:06 volumiohp sudo[4145]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:06 volumiohp sudo[4153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:06 volumiohp sudo[4153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:06 volumiohp sudo[4153]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 21:35:06 volumiohp volumio[1065]: info: Asound.conf file written Dec 17 21:35:06 volumiohp sudo[4164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 17 21:35:06 volumiohp sudo[4164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:06 volumiohp sudo[4164]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:06 volumiohp volumio[1065]: No state is present for card HDMI Dec 17 21:35:06 volumiohp volumio[1065]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100500" "0x103c" "0x21b4" Dec 17 21:35:06 volumiohp volumio[1065]: Hardware is initialized using a generic method Dec 17 21:35:06 volumiohp volumio[1065]: No state is present for card HDMI Dec 17 21:35:06 volumiohp volumio[1065]: No state is present for card Generic Dec 17 21:35:06 volumiohp volumio[1065]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0221,103c21b4,00100003" "0x103c" "0x21b4" Dec 17 21:35:06 volumiohp volumio[1065]: Hardware is initialized using a generic method Dec 17 21:35:06 volumiohp volumio[1065]: No state is present for card Generic Dec 17 21:35:06 volumiohp volumio[1065]: No state is present for card x20 Dec 17 21:35:06 volumiohp volumio[1065]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Dec 17 21:35:06 volumiohp volumio[1065]: Hardware is initialized using a generic method Dec 17 21:35:06 volumiohp volumio[1065]: No state is present for card x20 Dec 17 21:35:06 volumiohp volumio[1065]: info: Output device has changed, restarting MPD Dec 17 21:35:06 volumiohp volumio[1065]: info: Output device has changed, restarting Shairport Sync Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:06 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:07 volumiohp sudo[4170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 21:35:07 volumiohp sudo[4170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp sudo[4170]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp sudo[4172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 21:35:07 volumiohp sudo[4172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 21:35:07 volumiohp volumio[1065]: info: Output device has changed, restarting MPD Dec 17 21:35:07 volumiohp sudo[4180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:07 volumiohp sudo[4180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 17 21:35:07 volumiohp volumio[1065]: info: Output device has changed, restarting Shairport Sync Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:07 volumiohp systemd[1]: mpd.service: Deactivated successfully. Dec 17 21:35:07 volumiohp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 21:35:07 volumiohp systemd[1]: mpd.service: Consumed 1.333s CPU time. Dec 17 21:35:07 volumiohp sudo[4182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 21:35:07 volumiohp sudo[4182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp systemd[1]: mpd.socket: Deactivated successfully. Dec 17 21:35:07 volumiohp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 21:35:07 volumiohp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 21:35:07 volumiohp sudo[4185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 21:35:07 volumiohp sudo[4185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp sudo[4182]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 21:35:07 volumiohp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 21:35:07 volumiohp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 21:35:07 volumiohp sudo[4180]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp systemd[1]: mpd.service: Deactivated successfully. Dec 17 21:35:07 volumiohp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 21:35:07 volumiohp systemd[1]: mpd.socket: Deactivated successfully. Dec 17 21:35:07 volumiohp volumio[1065]: info: MPD Permissions set Dec 17 21:35:07 volumiohp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 21:35:07 volumiohp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 21:35:07 volumiohp volumio[1065]: info: MPD Permissions set Dec 17 21:35:07 volumiohp sudo[4195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp sudo[4195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp sudo[4197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:07 volumiohp sudo[4197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp sudo[4200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:07 volumiohp sudo[4200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 21:35:07 volumiohp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:07 volumiohp sudo[4197]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp volumio[1065]: info: Starting Shairport Sync Dec 17 21:35:07 volumiohp sudo[4195]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp volumio[1065]: info: Starting Shairport Sync Dec 17 21:35:07 volumiohp sudo[4200]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp sudo[4228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 21:35:07 volumiohp sudo[4228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp sudo[4212]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 17 21:35:07 volumiohp sudo[4212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 21:35:07 volumiohp sudo[4212]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp sudo[4231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 21:35:07 volumiohp sudo[4231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:07 volumiohp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 21:35:07 volumiohp systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 21:35:07 volumiohp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:35:07 volumiohp systemd[1]: shairport-sync.service: Consumed 2.431s CPU time. Dec 17 21:35:07 volumiohp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:35:07 volumiohp sudo[4228]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp sudo[4231]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:07 volumiohp volumio[1065]: info: Shairport-Sync Started Dec 17 21:35:07 volumiohp volumio[1065]: info: Shairport-Sync Started Dec 17 21:35:07 volumiohp kernel: usb 2-1: USB disconnect, device number 32 Dec 17 21:35:07 volumiohp volumio[1065]: info: Dec 17 21:35:07 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:07 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 17 21:35:08 volumiohp kernel: usb 2-1: new high-speed USB device number 33 using ehci-pci Dec 17 21:35:08 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:35:08 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:35:08 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:35:08 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:35:08 volumiohp (udev-worker)[4261]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:35:08 volumiohp volumio[1065]: info: Dec 17 21:35:08 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::Close All Modals sent Dec 17 21:35:08 volumiohp volumio[1065]: info: Preparing to save Alsa Options, stopping services first Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:35:08 volumiohp volumio[1065]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:08 volumiohp volumio[1065]: info: Device 5 does not have any Mixer Control Available Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 17 21:35:08 volumiohp volumio[1065]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 17 21:35:08 volumiohp volumio[1065]: info: Disabling external Volume Control Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:35:08 volumiohp sudo[4279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:08 volumiohp sudo[4279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:08 volumiohp volumio[1065]: info: Preparing to generate the ALSA configuration file Dec 17 21:35:08 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:35:08 volumiohp sudo[4299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:08 volumiohp sudo[4299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:08 volumiohp mpd[4235]: 2025-12-17T21:35:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 21:35:08 volumiohp sudo[4279]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:08 volumiohp systemd[1]: Started mpd.service - Music Player Daemon. Dec 17 21:35:08 volumiohp sudo[4172]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:08 volumiohp sudo[4185]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:08 volumiohp sudo[4299]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 21:35:08 volumiohp volumio[1065]: info: Asound.conf file unchanged, so no further update is needed Dec 17 21:35:08 volumiohp volumio[1065]: info: Output device has changed, restarting MPD Dec 17 21:35:08 volumiohp volumio[1065]: info: Output device has changed, restarting Shairport Sync Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:08 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:08 volumiohp sudo[4313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 21:35:08 volumiohp sudo[4313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:08 volumiohp sudo[4313]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:08 volumiohp sudo[4315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 21:35:08 volumiohp sudo[4315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:08 volumiohp volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 21:35:08 volumiohp systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 17 21:35:08 volumiohp volumio[1065]: info: MPD Permissions set Dec 17 21:35:08 volumiohp systemd[1]: mpd.service: Deactivated successfully. Dec 17 21:35:08 volumiohp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 21:35:08 volumiohp systemd[1]: mpd.service: Consumed 1.446s CPU time. Dec 17 21:35:08 volumiohp sudo[4323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:08 volumiohp sudo[4323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:08 volumiohp kernel: usb 2-1: USB disconnect, device number 33 Dec 17 21:35:08 volumiohp systemd[1]: mpd.socket: Deactivated successfully. Dec 17 21:35:08 volumiohp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 21:35:08 volumiohp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 21:35:08 volumiohp sudo[4325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:09 volumiohp sudo[4325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:09 volumiohp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 21:35:09 volumiohp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 21:35:09 volumiohp volumio[1065]: error: MPD error: Error: connect ECONNRESET /run/mpd/socket Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 21:35:09 volumiohp sudo[4323]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 17 21:35:09 volumiohp sudo[4325]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:09 volumiohp volumio[1065]: info: Dec 17 21:35:09 volumiohp volumio[1065]: ---------------------------- USB Audio Device Detached Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 17 21:35:09 volumiohp volumio[1065]: info: Starting Shairport Sync Dec 17 21:35:09 volumiohp sudo[4331]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 17 21:35:09 volumiohp sudo[4331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 21:35:09 volumiohp sudo[4331]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:09 volumiohp sudo[4346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 21:35:09 volumiohp sudo[4346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:09 volumiohp kernel: usb 2-1: new high-speed USB device number 34 using ehci-pci Dec 17 21:35:09 volumiohp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 21:35:09 volumiohp systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 21:35:09 volumiohp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:35:09 volumiohp systemd[1]: shairport-sync.service: Consumed 1.486s CPU time. Dec 17 21:35:09 volumiohp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:35:09 volumiohp sudo[4346]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:09 volumiohp volumio[1065]: info: Shairport-Sync Started Dec 17 21:35:09 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:35:09 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:35:09 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:35:09 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:35:09 volumiohp (udev-worker)[4261]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 17 21:35:09 volumiohp volumio[1065]: info: Dec 17 21:35:09 volumiohp volumio[1065]: ---------------------------- USB Audio Device Attached Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::Close All Modals sent Dec 17 21:35:09 volumiohp volumio[1065]: info: Preparing to save Alsa Options, stopping services first Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::volumioGetState Dec 17 21:35:09 volumiohp volumio[1065]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:09 volumiohp volumio[1065]: info: Device 5 does not have any Mixer Control Available Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 17 21:35:09 volumiohp volumio[1065]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 17 21:35:09 volumiohp volumio[1065]: info: Disabling external Volume Control Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreStateMachine::pushState Dec 17 21:35:09 volumiohp volumio[1065]: info: CoreCommandRouter::volumioPushState Dec 17 21:35:09 volumiohp volumio[1065]: info: Preparing to generate the ALSA configuration file Dec 17 21:35:09 volumiohp sudo[4382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:09 volumiohp sudo[4382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:09 volumiohp volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dfX9XI3XRhNJw9AYoA01hgUXoFO2.ea5329df4cf4b868fa44a93af704d4f5.state.status' Dec 17 21:35:09 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:35:09 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:35:09 volumiohp sudo[4382]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:09 volumiohp sudo[4388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:09 volumiohp sudo[4388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp sudo[4388]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 21:35:10 volumiohp volumio[1065]: info: Asound.conf file unchanged, so no further update is needed Dec 17 21:35:10 volumiohp volumio[1065]: info: Output device has changed, restarting MPD Dec 17 21:35:10 volumiohp volumio[1065]: info: Output device has changed, restarting Shairport Sync Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 21:35:10 volumiohp sudo[4403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 21:35:10 volumiohp sudo[4411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:10 volumiohp sudo[4411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:35:10 volumiohp sudo[4403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp sudo[4406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 21:35:10 volumiohp volumio[1065]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:35:10 volumiohp volumio[1065]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 17 21:35:10 volumiohp sudo[4406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp sudo[4414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 17 21:35:10 volumiohp sudo[4414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp sudo[4403]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp systemd[1]: mpd.service: Deactivated successfully. Dec 17 21:35:10 volumiohp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 21:35:10 volumiohp systemd[1]: mpd.service: Consumed 1.107s CPU time. Dec 17 21:35:10 volumiohp systemd[1]: mpd.socket: Deactivated successfully. Dec 17 21:35:10 volumiohp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 21:35:10 volumiohp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 21:35:10 volumiohp sudo[4423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 17 21:35:10 volumiohp sudo[4423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 21:35:10 volumiohp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 21:35:10 volumiohp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 21:35:10 volumiohp sudo[4414]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp volumio[1065]: info: MPD Permissions set Dec 17 21:35:10 volumiohp volumio[1065]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 17 21:35:10 volumiohp volumio[1065]: error: MPD error: Error: read ECONNRESET Dec 17 21:35:10 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:35:10 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:35:10 volumiohp kernel: usb 2-1: USB disconnect, device number 34 Dec 17 21:35:10 volumiohp systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 17 21:35:10 volumiohp volumio[1065]: info: TidalConnect service stoped! Dec 17 21:35:10 volumiohp sudo[4423]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp sudo[4411]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp sudo[4433]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 17 21:35:10 volumiohp sudo[4433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 21:35:10 volumiohp sudo[4433]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp volumio[1065]: info: Starting Shairport Sync Dec 17 21:35:10 volumiohp volumio[1065]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 21:35:10 volumiohp volumio[1065]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 17 21:35:10 volumiohp volumio[1065]: Job for vtcs.service canceled. Dec 17 21:35:10 volumiohp volumio[1065]: at ChildProcess.exithandler (node:child_process:421:12) Dec 17 21:35:10 volumiohp volumio[1065]: at ChildProcess.emit (node:events:514:28) Dec 17 21:35:10 volumiohp volumio[1065]: at maybeClose (node:internal/child_process:1105:16) Dec 17 21:35:10 volumiohp volumio[1065]: at Socket. (node:internal/child_process:457:11) Dec 17 21:35:10 volumiohp volumio[1065]: at Socket.emit (node:events:514:28) Dec 17 21:35:10 volumiohp volumio[1065]: at Pipe. (node:net:337:12) { Dec 17 21:35:10 volumiohp volumio[1065]: code: 1, Dec 17 21:35:10 volumiohp volumio[1065]: killed: false, Dec 17 21:35:10 volumiohp volumio[1065]: signal: null, Dec 17 21:35:10 volumiohp volumio[1065]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 17 21:35:10 volumiohp volumio[1065]: stdout: '', Dec 17 21:35:10 volumiohp volumio[1065]: stderr: 'Job for vtcs.service canceled.\n' Dec 17 21:35:10 volumiohp volumio[1065]: } Dec 17 21:35:10 volumiohp volumio[1065]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 21:35:10 volumiohp sudo[4457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 21:35:10 volumiohp sudo[4457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 21:35:10 volumiohp kernel: usb 2-1: new high-speed USB device number 35 using ehci-pci Dec 17 21:35:10 volumiohp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 21:35:10 volumiohp systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 21:35:10 volumiohp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:35:10 volumiohp systemd[1]: shairport-sync.service: Consumed 1.256s CPU time. Dec 17 21:35:10 volumiohp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 21:35:10 volumiohp sudo[4457]: pam_unix(sudo:session): session closed for user root Dec 17 21:35:10 volumiohp kernel: usb 2-1: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= e.20 Dec 17 21:35:10 volumiohp kernel: usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 21:35:10 volumiohp kernel: usb 2-1: Product: xCORE USB Audio 2.0 Dec 17 21:35:10 volumiohp kernel: usb 2-1: Manufacturer: XMOS Dec 17 21:35:11 volumiohp sudo[4492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-17 21:34' Dec 17 21:35:11 volumiohp sudo[4492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"