Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: new high-speed USB device number 5 using xhci_hcd Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: config 1 interface 2 altsetting 0 endpoint 0x5 has an invalid bInterval 0, changing to 7 Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: New USB device found, idVendor=2972, idProduct=0053, bcdDevice=9f.dd Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: Product: FiiO KA3 Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: Manufacturer: FiiO Feb 22 01:18:09 volumio-z8350 kernel: usb 1-2: SerialNumber: FiiO KA3 Feb 22 01:18:09 volumio-z8350 kernel: input: FiiO FiiO KA3 as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2/0003:2972:0053.0004/input/input13 Feb 22 01:18:09 volumio-z8350 kernel: hid-generic 0003:2972:0053.0004: input,hidraw0: USB HID v1.11 Device [FiiO FiiO KA3] on usb-0000:00:14.0-2/input2 Feb 22 01:18:09 volumio-z8350 kernel: usbcore: registered new interface driver snd-usb-audio Feb 22 01:18:09 volumio-z8350 (udev-worker)[7405]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Feb 22 01:18:09 volumio-z8350 volumio[6288]: info: Feb 22 01:18:09 volumio-z8350 volumio[6288]: ---------------------------- USB Audio Device Attached Feb 22 01:18:09 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 22 01:18:17 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetState Feb 22 01:18:17 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 01:18:24 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Preparing to save Alsa Options, stopping services first Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetState Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPause Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreStateMachine::pause Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO KA3"}} Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Setting default mixerSoftMaster for Softvolume device Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Disabling external Volume Control Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Enable softmixer device for audio device undefined Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Software Volume ALSA configuration written Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Preparing to generate the ALSA configuration file Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Reading ALSA contributions from plugins. Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:32 volumio-z8350 volumio[6288]: info: Asound.conf file written Feb 22 01:18:32 volumio-z8350 sudo[7527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 01:18:32 volumio-z8350 sudo[7527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:32 volumio-z8350 sudo[7527]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:33 volumio-z8350 volumio[6288]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 01:18:33 volumio-z8350 volumio[6288]: No state is present for card Audio Feb 22 01:18:33 volumio-z8350 volumio[6288]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 01:18:33 volumio-z8350 volumio[6288]: Found hardware: "HdmiLpeAudio" "" "" "" "" Feb 22 01:18:33 volumio-z8350 volumio[6288]: Hardware is initialized using a generic method Feb 22 01:18:33 volumio-z8350 volumio[6288]: No state is present for card Audio Feb 22 01:18:33 volumio-z8350 volumio[6288]: alsa-lib main.c:844:(execute_sequence) exec '/bin/rm -rf /var/lib/alsa/card1.conf.d' failed (exit code 1) Feb 22 01:18:33 volumio-z8350 volumio[6288]: No state is present for card bytcrrt5651 Feb 22 01:18:33 volumio-z8350 volumio[6288]: Found hardware: "bytcr-rt5651" "" "cfg-spk:2 cfg-mic:in2" "" "" Feb 22 01:18:33 volumio-z8350 volumio[6288]: Hardware is initialized using a generic method Feb 22 01:18:33 volumio-z8350 volumio[6288]: No state is present for card bytcrrt5651 Feb 22 01:18:33 volumio-z8350 volumio[6288]: No state is present for card KA3 Feb 22 01:18:33 volumio-z8350 volumio[6288]: Found hardware: "USB-Audio" "USB Mixer" "USB2972:0053" "" "" Feb 22 01:18:33 volumio-z8350 volumio[6288]: Hardware is initialized using a generic method Feb 22 01:18:33 volumio-z8350 volumio[6288]: No state is present for card KA3 Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Output device has changed, restarting MPD Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Output device has changed, restarting Shairport Sync Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 01:18:33 volumio-z8350 sudo[7535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 01:18:33 volumio-z8350 sudo[7535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:33 volumio-z8350 sudo[7535]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:33 volumio-z8350 sudo[7537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 01:18:33 volumio-z8350 sudo[7537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Output device has changed, restarting MPD Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Output device has changed, restarting Shairport Sync Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 01:18:33 volumio-z8350 sudo[7545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 01:18:33 volumio-z8350 sudo[7545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:33 volumio-z8350 sudo[7545]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:33 volumio-z8350 sudo[7548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 01:18:33 volumio-z8350 sudo[7548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: VolumeController::SetAlsaVolume0 Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 01:18:33 volumio-z8350 systemd[1]: mpd.service: Deactivated successfully. Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 01:18:33 volumio-z8350 systemd[1]: mpd.service: Consumed 1.898s CPU time. Feb 22 01:18:33 volumio-z8350 systemd[1]: mpd.socket: Deactivated successfully. Feb 22 01:18:33 volumio-z8350 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: MPD Permissions set Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: MPD Permissions set Feb 22 01:18:33 volumio-z8350 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 01:18:33 volumio-z8350 volumio[6288]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 01:18:33 volumio-z8350 systemd[1]: mpd.service: Deactivated successfully. Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 01:18:33 volumio-z8350 systemd[1]: mpd.socket: Deactivated successfully. Feb 22 01:18:33 volumio-z8350 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 01:18:33 volumio-z8350 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 01:18:33 volumio-z8350 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Starting Shairport Sync Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Starting Shairport Sync Feb 22 01:18:33 volumio-z8350 sudo[7577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 01:18:33 volumio-z8350 sudo[7577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:33 volumio-z8350 sudo[7579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 01:18:33 volumio-z8350 sudo[7579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 01:18:33 volumio-z8350 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 01:18:33 volumio-z8350 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 01:18:33 volumio-z8350 systemd[1]: shairport-sync.service: Consumed 3.792s CPU time. Feb 22 01:18:33 volumio-z8350 sudo[7563]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 01:18:33 volumio-z8350 sudo[7563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 01:18:33 volumio-z8350 sudo[7563]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:33 volumio-z8350 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 01:18:33 volumio-z8350 sudo[7577]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:33 volumio-z8350 sudo[7579]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Shairport-Sync Started Feb 22 01:18:33 volumio-z8350 volumio[6288]: info: Shairport-Sync Started Feb 22 01:18:35 volumio-z8350 mpd[7584]: 2026-02-22T01:18:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 01:18:35 volumio-z8350 systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 01:18:35 volumio-z8350 sudo[7537]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:35 volumio-z8350 sudo[7548]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:35 volumio-z8350 volumio[6288]: error: MPD error: The expression evaluated to a falsy value: Feb 22 01:18:35 volumio-z8350 volumio[6288]: assert.ok(self.idling) Feb 22 01:18:35 volumio-z8350 volumio[6288]: error: The expression evaluated to a falsy value: Feb 22 01:18:35 volumio-z8350 volumio[6288]: assert.ok(self.idling) Feb 22 01:18:35 volumio-z8350 volumio[6288]: error: updateQueue error: null Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreStateMachine::getcurrentVolume Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreStateMachine::updateTrackBlock Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrackBlock Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:36 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:38 volumio-z8350 sudo[7619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 01:18:38 volumio-z8350 sudo[7619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:38 volumio-z8350 sudo[7621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 01:18:38 volumio-z8350 sudo[7621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 01:18:38 volumio-z8350 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 22 01:18:38 volumio-z8350 systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 22 01:18:38 volumio-z8350 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 22 01:18:38 volumio-z8350 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 22 01:18:38 volumio-z8350 sudo[7619]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:38 volumio-z8350 volumio[6288]: info: Successfully started MPD Monitor Feb 22 01:18:38 volumio-z8350 sudo[7621]: pam_unix(sudo:session): session closed for user root Feb 22 01:18:38 volumio-z8350 mpd_monitor.sh[7624]: MPD Monitor Service: Starting MPD Monitor Service Feb 22 01:18:38 volumio-z8350 volumio[6288]: info: Successfully started MPD Monitor Feb 22 01:18:39 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetState Feb 22 01:18:39 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPlay Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::play index undefined Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::startPlaybackTimer Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: [1771694322240] ControllerUPNPBrowser::clearAddPlayTrack Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand stop took 1 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces system playlist update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Ignoring MPD Status Update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand clear took 2 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac" Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces system playlist update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Ignoring MPD Status Update Feb 22 01:18:42 volumio-z8350 volumio[6288]: error: updateQueue error: null Feb 22 01:18:42 volumio-z8350 volumio[6288]: error: updateQueue error: null Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 34ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac" Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 32ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces system playlist update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Ignoring MPD Status Update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand add "http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac" took 8 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces system playlist update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Ignoring MPD Status Update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 7ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand play took 4 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 4ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces state update: player Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::getState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand status Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces state update: player Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::getState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand status Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces state update: player Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand status took 19 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::getState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand status Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces state update: player Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::getState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand status Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand status took 25 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand status took 8 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseTrackInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::servicePushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","artist":null,"album":null,"uri":"http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","trackType":"flac"} Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: CURRENT POSITION 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState stateService play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState currentStatus stop Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 35ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces system playlist update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Ignoring MPD Status Update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces state update: player Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand status took 11 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::getState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand status Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseTrackInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseTrackInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::servicePushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","artist":null,"album":null,"uri":"http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","trackType":"flac"} Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: CURRENT POSITION 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState stateService play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState currentStatus play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Received an update from plugin. extracting info from payload Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::servicePushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","artist":null,"album":null,"uri":"http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","trackType":"flac"} Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: CURRENT POSITION 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState stateService play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState currentStatus play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Received an update from plugin. extracting info from payload Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 128ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 112ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces system playlist update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Ignoring MPD Status Update Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Feb 22 01:18:42 volumio-z8350 volumio[6288]: ---------------------------- MPD announces state update: player Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::getState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand status Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 101ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand status took 98 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand playlistinfo took 99 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 6ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand status took 6 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseTrackInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseState Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::servicePushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Diamonds And Rust","artist":"Joan Baez","album":"Diamonds And Rust In The Bullring","uri":"http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","trackType":"flac"} Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: CURRENT POSITION 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState stateService play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState currentStatus play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Received an update from plugin. extracting info from payload Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 156ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand playlistinfo took 96 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: sendMpdCommand playlistinfo took 95 milliseconds Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseTrackInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: ControllerMpd::parseTrackInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::servicePushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"Diamonds And Rust","artist":"Joan Baez","album":"Diamonds And Rust In The Bullring","uri":"http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","trackType":"flac"} Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: CURRENT POSITION 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState stateService play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState currentStatus play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Received an update from plugin. extracting info from payload Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ControllerMpd::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::servicePushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getTrack 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"Diamonds And Rust","artist":"Joan Baez","album":"Diamonds And Rust In The Bullring","uri":"http://192.168.1.123:9790/minimserver/*/music01/*5bSelection*5d/Joan*20Baze*20-*20Diamonds*20And*20Rust.flac","trackType":"flac"} Feb 22 01:18:42 volumio-z8350 volumio[6288]: verbose: CURRENT POSITION 3 Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState stateService play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::syncState currentStatus play Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: Received an update from plugin. extracting info from payload Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::pushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioPushState Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 305ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: ------------------------------ 211ms Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreCommandRouter::volumioGetQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CoreStateMachine::getQueue Feb 22 01:18:42 volumio-z8350 volumio[6288]: info: CorePlayQueue::getQueue Feb 22 01:19:09 volumio-z8350 volumio[6288]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 01:19:09 volumio-z8350 volumio[6288]: Error: connect ETIMEDOUT 67.228.235.93:443 Feb 22 01:19:09 volumio-z8350 volumio[6288]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 22 01:19:09 volumio-z8350 volumio[6288]: errno: -110, Feb 22 01:19:09 volumio-z8350 volumio[6288]: code: 'ETIMEDOUT', Feb 22 01:19:09 volumio-z8350 volumio[6288]: syscall: 'connect', Feb 22 01:19:09 volumio-z8350 volumio[6288]: address: '67.228.235.93', Feb 22 01:19:09 volumio-z8350 volumio[6288]: port: 443 Feb 22 01:19:09 volumio-z8350 volumio[6288]: } Feb 22 01:19:09 volumio-z8350 volumio[6288]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 01:19:10 volumio-z8350 sudo[7736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 01:18' Feb 22 01:19:10 volumio-z8350 sudo[7736]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 12:08:48 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6d6c21eab1a48cdba6d5b0d2a95c235b"