-- Logs begin at Thu 2025-06-26 15:40:15 CST, end at Thu 2025-06-26 15:43:10 CST. -- Jun 26 15:42:15 volumio kernel: usb 1-7: new high-speed USB device number 3 using xhci_hcd Jun 26 15:42:15 volumio kernel: usb 1-7: New USB device found, idVendor=22e1, idProduct=ca01, bcdDevice= 1.01 Jun 26 15:42:15 volumio kernel: usb 1-7: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 26 15:42:15 volumio kernel: usb 1-7: Product: Serenade DSD Jun 26 15:42:15 volumio kernel: usb 1-7: Manufacturer: TempoTec Corp. Jun 26 15:42:15 volumio kernel: input: TempoTec Corp. Serenade DSD as /devices/pci0000:00/0000:00:15.0/usb1/1-7/1-7:1.2/0003:22E1:CA01.0001/input/input11 Jun 26 15:42:15 volumio kernel: hid-generic 0003:22E1:CA01.0001: input,hidraw0: USB HID v1.00 Device [TempoTec Corp. Serenade DSD] on usb-0000:00:15.0-7/input2 Jun 26 15:42:15 volumio kernel: usb 1-7: 1:3 : unsupported format bits 0x100000000 Jun 26 15:42:15 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jun 26 15:42:15 volumio systemd-udevd[1910]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 15:42:15 volumio volumio[1139]: info: Jun 26 15:42:15 volumio volumio[1139]: ---------------------------- USB Audio Device Attached Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jun 26 15:42:15 volumio volumio[1139]: info: Preparing to save Alsa Options, stopping services first Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 26 15:42:15 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::volumioPause Jun 26 15:42:15 volumio volumio[1139]: info: CoreStateMachine::pause Jun 26 15:42:15 volumio volumio[1139]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Serenade DSD","alsacard":"DSD"},"i2s":false} Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:15 volumio volumio[1139]: info: Setting default mixerSoftMaster for Softvolume device Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 26 15:42:15 volumio volumio[1139]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 26 15:42:15 volumio volumio[1139]: info: Disabling external Volume Control Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 15:42:15 volumio volumio[1139]: info: Enable softmixer device for audio device undefined Jun 26 15:42:15 volumio volumio[1139]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 15:42:15 volumio volumio[1139]: info: Software Volume ALSA configuration written Jun 26 15:42:15 volumio volumio[1139]: info: Preparing to generate the ALSA configuration file Jun 26 15:42:15 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:15 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:15 volumio volumio[1139]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 26 15:42:15 volumio volumio[1139]: info: Reading ALSA contributions from plugins. Jun 26 15:42:15 volumio volumio[1139]: info: Asound.conf file written Jun 26 15:42:15 volumio sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 26 15:42:15 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:15 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:15 volumio volumio[1139]: info: Output device has changed, restarting MPD Jun 26 15:42:15 volumio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 15:42:15 volumio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:15 volumio sudo[1949]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:15 volumio volumio[1139]: info: Output device has changed, restarting Shairport Sync Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:15 volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 15:42:15 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:15 volumio systemd[1]: Stopping Music Player Daemon... Jun 26 15:42:15 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 15:42:15 volumio volumio[1139]: info: Output device has changed, restarting MPD Jun 26 15:42:15 volumio systemd[1]: mpd.service: Succeeded. Jun 26 15:42:15 volumio systemd[1]: Stopped Music Player Daemon. Jun 26 15:42:15 volumio sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 15:42:15 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:15 volumio sudo[1958]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:15 volumio volumio[1139]: info: Output device has changed, restarting Shairport Sync Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:15 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:15 volumio sudo[1962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 15:42:15 volumio sudo[1962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:15 volumio systemd[1]: Starting Music Player Daemon... Jun 26 15:42:16 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 15:42:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 26 15:42:16 volumio systemd[1]: mpd.service: Succeeded. Jun 26 15:42:16 volumio systemd[1]: Stopped Music Player Daemon. Jun 26 15:42:16 volumio volumio[1139]: info: MPD Permissions set Jun 26 15:42:16 volumio volumio[1139]: info: MPD Permissions set Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio systemd[1]: Starting Music Player Daemon... Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio sudo[1967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 15:42:16 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:16 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:16 volumio volumio[1139]: info: Starting Shairport Sync Jun 26 15:42:16 volumio volumio[1139]: info: Starting Shairport Sync Jun 26 15:42:16 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 15:42:16 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 15:42:16 volumio sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 15:42:16 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 26 15:42:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:16 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:16 volumio volumio[1139]: info: Shairport-Sync Started Jun 26 15:42:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 15:42:16 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 26 15:42:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 26 15:42:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:16 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:16 volumio volumio[1139]: info: Shairport-Sync Started Jun 26 15:42:16 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 26 15:42:16 volumio mpd[1979]: Jun 26 15:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 15:42:17 volumio systemd[1]: Started Music Player Daemon. Jun 26 15:42:17 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:17 volumio sudo[1962]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:17 volumio volumio[1139]: error: MPD error: The expression evaluated to a falsy value: Jun 26 15:42:17 volumio volumio[1139]: assert.ok(self.idling) Jun 26 15:42:17 volumio volumio[1139]: error: The expression evaluated to a falsy value: Jun 26 15:42:17 volumio volumio[1139]: assert.ok(self.idling) Jun 26 15:42:17 volumio volumio[1139]: error: updateQueue error: null Jun 26 15:42:17 volumio kernel: usb 1-7: USB disconnect, device number 3 Jun 26 15:42:17 volumio volumio[1139]: info: Jun 26 15:42:17 volumio volumio[1139]: ---------------------------- USB Audio Device Detached Jun 26 15:42:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 26 15:42:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:17 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 26 15:42:19 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 26 15:42:19 volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jun 26 15:42:19 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 15:42:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:19 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:19 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jun 26 15:42:19 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jun 26 15:42:19 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 15:42:19 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:19 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:19 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:23 volumio kernel: usb 1-7: new high-speed USB device number 4 using xhci_hcd Jun 26 15:42:24 volumio kernel: usb 1-7: New USB device found, idVendor=22e1, idProduct=ca01, bcdDevice= 1.01 Jun 26 15:42:24 volumio kernel: usb 1-7: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 26 15:42:24 volumio kernel: usb 1-7: Product: Serenade DSD Jun 26 15:42:24 volumio kernel: usb 1-7: Manufacturer: TempoTec Corp. Jun 26 15:42:24 volumio kernel: usb 1-7: 1:3 : unsupported format bits 0x100000000 Jun 26 15:42:24 volumio kernel: input: TempoTec Corp. Serenade DSD as /devices/pci0000:00/0000:00:15.0/usb1/1-7/1-7:1.2/0003:22E1:CA01.0002/input/input12 Jun 26 15:42:24 volumio kernel: hid-generic 0003:22E1:CA01.0002: input,hidraw0: USB HID v1.00 Device [TempoTec Corp. Serenade DSD] on usb-0000:00:15.0-7/input2 Jun 26 15:42:24 volumio volumio[1139]: info: Jun 26 15:42:24 volumio volumio[1139]: ---------------------------- USB Audio Device Attached Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::Close All Modals sent Jun 26 15:42:24 volumio volumio[1139]: info: Preparing to save Alsa Options, stopping services first Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 26 15:42:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::volumioPause Jun 26 15:42:24 volumio volumio[1139]: info: CoreStateMachine::pause Jun 26 15:42:24 volumio volumio[1139]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Serenade DSD","alsacard":"DSD"},"i2s":false} Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:24 volumio systemd-udevd[2050]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 15:42:24 volumio volumio[1139]: info: Setting default mixerSoftMaster for Softvolume device Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 26 15:42:24 volumio volumio[1139]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 26 15:42:24 volumio volumio[1139]: info: Disabling external Volume Control Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 15:42:24 volumio volumio[1139]: info: Enable softmixer device for audio device undefined Jun 26 15:42:24 volumio volumio[1139]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 15:42:24 volumio volumio[1139]: info: Software Volume ALSA configuration written Jun 26 15:42:24 volumio volumio[1139]: info: Preparing to generate the ALSA configuration file Jun 26 15:42:24 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:24 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:24 volumio volumio[1139]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 26 15:42:24 volumio volumio[1139]: info: Reading ALSA contributions from plugins. Jun 26 15:42:24 volumio volumio[1139]: info: Asound.conf file unchanged, so no further update is needed Jun 26 15:42:24 volumio volumio[1139]: info: Output device has changed, restarting MPD Jun 26 15:42:24 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 15:42:24 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:24 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:24 volumio volumio[1139]: info: Output device has changed, restarting Shairport Sync Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:24 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 15:42:24 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:24 volumio systemd[1]: Stopping Music Player Daemon... Jun 26 15:42:24 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 15:42:24 volumio volumio[1139]: info: MPD Permissions set Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 15:42:24 volumio systemd[1]: mpd.service: Succeeded. Jun 26 15:42:24 volumio systemd[1]: Stopped Music Player Daemon. Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:24 volumio volumio[1139]: info: Starting Shairport Sync Jun 26 15:42:24 volumio systemd[1]: Starting Music Player Daemon... Jun 26 15:42:24 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 15:42:24 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 15:42:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 26 15:42:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:24 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:24 volumio volumio[1139]: info: Shairport-Sync Started Jun 26 15:42:24 volumio volumio[1139]: (node:1139) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 26 15:42:24 volumio volumio[1139]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 26 15:42:24 volumio volumio[1139]: (node:1139) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 26 15:42:24 volumio volumio[1139]: (node:1139) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 26 15:42:24 volumio volumio[1139]: (node:1139) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 26 15:42:24 volumio volumio[1139]: (node:1139) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 26 15:42:24 volumio sudo[2092]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 15:42:24 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:24 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:24 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 26 15:42:25 volumio mpd[2100]: Jun 26 15:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 15:42:25 volumio systemd[1]: Started Music Player Daemon. Jun 26 15:42:25 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:25 volumio volumio[1139]: error: updateQueue error: null Jun 26 15:42:28 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 26 15:42:28 volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jun 26 15:42:28 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 15:42:28 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:28 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:28 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:28 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:28 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jun 26 15:42:28 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jun 26 15:42:28 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 15:42:28 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:28 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:28 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:42 volumio volumio[1139]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 26 15:42:42 volumio volumio[1139]: info: Preparing to save Alsa Options, stopping services first Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::volumioGetState Jun 26 15:42:42 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::volumioPause Jun 26 15:42:42 volumio volumio[1139]: info: CoreStateMachine::pause Jun 26 15:42:42 volumio volumio[1139]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Serenade DSD"}} Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:42 volumio volumio[1139]: info: Setting default mixerSoftMaster for Softvolume device Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 26 15:42:42 volumio volumio[1139]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 26 15:42:42 volumio volumio[1139]: info: Disabling external Volume Control Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 15:42:42 volumio volumio[1139]: info: Enable softmixer device for audio device undefined Jun 26 15:42:42 volumio volumio[1139]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 15:42:42 volumio volumio[1139]: info: Software Volume ALSA configuration written Jun 26 15:42:42 volumio volumio[1139]: info: Preparing to generate the ALSA configuration file Jun 26 15:42:42 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:42 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:42 volumio volumio[1139]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 26 15:42:42 volumio volumio[1139]: info: Reading ALSA contributions from plugins. Jun 26 15:42:42 volumio volumio[1139]: info: Asound.conf file unchanged, so no further update is needed Jun 26 15:42:42 volumio volumio[1139]: info: Output device has changed, restarting MPD Jun 26 15:42:42 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 15:42:42 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:42 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:42 volumio volumio[1139]: info: Output device has changed, restarting Shairport Sync Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:42 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 15:42:42 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:42 volumio systemd[1]: Stopping Music Player Daemon... Jun 26 15:42:42 volumio volumio[1139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 15:42:42 volumio volumio[1139]: info: MPD Permissions set Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio systemd[1]: mpd.service: Succeeded. Jun 26 15:42:42 volumio systemd[1]: Stopped Music Player Daemon. Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 15:42:42 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 15:42:42 volumio volumio[1139]: info: Starting Shairport Sync Jun 26 15:42:42 volumio systemd[1]: Starting Music Player Daemon... Jun 26 15:42:42 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 15:42:42 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 15:42:42 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 26 15:42:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 15:42:42 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:42 volumio volumio[1139]: info: Shairport-Sync Started Jun 26 15:42:42 volumio sudo[2149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 15:42:42 volumio sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 15:42:42 volumio sudo[2149]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:43 volumio mpd[2159]: Jun 26 15:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 15:42:43 volumio systemd[1]: Started Music Player Daemon. Jun 26 15:42:43 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Jun 26 15:42:43 volumio volumio[1139]: error: updateQueue error: null Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::getcurrentVolume Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::updateTrackBlock Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrackBlock Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioPlay Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::play index undefined Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::startPlaybackTimer Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/古璇 粤听粤好听/今宵多珍重 - 古璇.mp3 Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand stop took 2 milliseconds Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand clear Jun 26 15:42:46 volumio volumio[1139]: info: Jun 26 15:42:46 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jun 26 15:42:46 volumio volumio[1139]: info: Ignoring MPD Status Update Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand clear took 1 milliseconds Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/古璇 粤听粤好听/今宵多珍重 - 古璇.mp3" Jun 26 15:42:46 volumio volumio[1139]: error: updateQueue error: null Jun 26 15:42:46 volumio volumio[1139]: info: Jun 26 15:42:46 volumio volumio[1139]: ---------------------------- MPD announces system playlist update Jun 26 15:42:46 volumio volumio[1139]: info: Ignoring MPD Status Update Jun 26 15:42:46 volumio volumio[1139]: info: ------------------------------ 3ms Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand add "NAS/music/古璇 粤听粤好听/今宵多珍重 - 古璇.mp3" took 2 milliseconds Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand play Jun 26 15:42:46 volumio volumio[1139]: info: ------------------------------ 2ms Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand play took 2 milliseconds Jun 26 15:42:46 volumio volumio[1139]: info: Jun 26 15:42:46 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jun 26 15:42:46 volumio volumio[1139]: info: ControllerMpd::getState Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jun 26 15:42:46 volumio volumio[1139]: info: Jun 26 15:42:46 volumio volumio[1139]: ---------------------------- MPD announces state update: player Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand status took 19 milliseconds Jun 26 15:42:46 volumio volumio[1139]: info: ControllerMpd::getState Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand status Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::parseState Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand status took 2 milliseconds Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::parseState Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jun 26 15:42:46 volumio volumio[1139]: info: ControllerMpd::pushState Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"今宵多珍重","artist":"古璇","album":"粤听粤好听","uri":"NAS/music/古璇 粤听粤好听/今宵多珍重 - 古璇.mp3","trackType":"mp3"} Jun 26 15:42:46 volumio volumio[1139]: verbose: CURRENT POSITION 0 Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus stop Jun 26 15:42:46 volumio volumio[1139]: info: ------------------------------ 26ms Jun 26 15:42:46 volumio volumio[1139]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 26 15:42:46 volumio volumio[1139]: verbose: ControllerMpd::parseTrackInfo Jun 26 15:42:46 volumio volumio[1139]: info: ControllerMpd::pushState Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"今宵多珍重","artist":"古璇","album":"粤听粤好听","uri":"NAS/music/古璇 粤听粤好听/今宵多珍重 - 古璇.mp3","trackType":"mp3"} Jun 26 15:42:46 volumio volumio[1139]: verbose: CURRENT POSITION 0 Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::syncState stateService play Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::syncState currentStatus play Jun 26 15:42:46 volumio volumio[1139]: info: Received an update from plugin. extracting info from payload Jun 26 15:42:46 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:46 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:42:46 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:47 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:42:47 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:42:47 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:42:47 volumio volumio[1139]: info: ------------------------------ 24ms Jun 26 15:43:02 volumio volumio[1139]: info: VolumeController::SetAlsaVolume0 Jun 26 15:43:02 volumio volumio[1139]: info: CoreStateMachine::pushState Jun 26 15:43:02 volumio volumio[1139]: info: CorePlayQueue::getTrack 0 Jun 26 15:43:02 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 15:43:02 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jun 26 15:43:02 volumio volumio[1139]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jun 26 15:43:10 volumio volumio[1139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 15:43:10 volumio volumio[1139]: Error: connect ETIMEDOUT 162.125.18.133:443 Jun 26 15:43:10 volumio volumio[1139]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 26 15:43:10 volumio volumio[1139]: errno: -110, Jun 26 15:43:10 volumio volumio[1139]: code: 'ETIMEDOUT', Jun 26 15:43:10 volumio volumio[1139]: syscall: 'connect', Jun 26 15:43:10 volumio volumio[1139]: address: '162.125.18.133', Jun 26 15:43:10 volumio volumio[1139]: port: 443 Jun 26 15:43:10 volumio volumio[1139]: } Jun 26 15:43:10 volumio volumio[1139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 15:43:10 volumio sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 15:42 Jun 26 15:43:10 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"