-- Logs begin at Sun 2025-12-21 08:31:25 CST, end at Sun 2025-12-21 10:53:12 CST. -- Dec 21 10:52:08 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:08 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:19 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:19 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:24 volumio kernel: usb 1-3: USB disconnect, device number 3 Dec 21 10:52:24 volumio volumio[7322]: info: Dec 21 10:52:24 volumio volumio[7322]: ---------------------------- USB Audio Device Detached Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 21 10:52:24 volumio kernel: usb 1-3: new high-speed USB device number 5 using xhci_hcd Dec 21 10:52:24 volumio kernel: usb 1-3: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Dec 21 10:52:24 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 21 10:52:24 volumio kernel: usb 1-3: Product: Combo384 Amanero Dec 21 10:52:24 volumio kernel: usb 1-3: Manufacturer: Amanero Technologies Dec 21 10:52:24 volumio kernel: usb 1-3: SerialNumber: 413-001 Dec 21 10:52:24 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:24 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Dec 21 10:52:24 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:24 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Dec 21 10:52:24 volumio volumio[7322]: info: Dec 21 10:52:24 volumio volumio[7322]: ---------------------------- USB Audio Device Attached Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:52:24 volumio volumio[7322]: info: Preparing to save Alsa Options, stopping services first Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:24 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::volumioPause Dec 21 10:52:24 volumio volumio[7322]: info: CoreStateMachine::pause Dec 21 10:52:24 volumio volumio[7322]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:24 volumio volumio[7322]: info: Setting mixer PCM for card Combo384 Amanero Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 10:52:24 volumio volumio[7322]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 10:52:24 volumio volumio[7322]: info: Disabling external Volume Control Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 10:52:24 volumio volumio[7322]: info: Preparing to generate the ALSA configuration file Dec 21 10:52:24 volumio volumio[7322]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 21 10:52:24 volumio volumio[7322]: info: Reading ALSA contributions from plugins. Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 10:52:24 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:24 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:24 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:24 volumio volumio[7322]: info: Asound.conf file unchanged, so no further update is needed Dec 21 10:52:24 volumio volumio[7322]: info: Output device has changed, restarting MPD Dec 21 10:52:24 volumio sudo[7959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 10:52:24 volumio sudo[7959]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:24 volumio volumio[7322]: info: Output device has changed, restarting Shairport Sync Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:24 volumio sudo[7959]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:24 volumio sudo[7961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 10:52:24 volumio sudo[7961]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:24 volumio systemd[1]: Stopping Music Player Daemon... Dec 21 10:52:24 volumio volumio[7322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 10:52:24 volumio volumio[7322]: info: MPD Permissions set Dec 21 10:52:24 volumio systemd[1]: mpd.service: Succeeded. Dec 21 10:52:24 volumio systemd[1]: Stopped Music Player Daemon. Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:24 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 10:52:25 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:25 volumio volumio[7322]: info: Starting Shairport Sync Dec 21 10:52:25 volumio sudo[7975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 10:52:25 volumio sudo[7975]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:25 volumio systemd[1]: Starting Music Player Daemon... Dec 21 10:52:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 10:52:25 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 21 10:52:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:25 volumio sudo[7975]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:25 volumio volumio[7322]: info: Shairport-Sync Started Dec 21 10:52:25 volumio sudo[7967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 10:52:25 volumio sudo[7967]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:25 volumio sudo[7967]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:25 volumio mpd[7981]: Dec 21 10:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 10:52:25 volumio systemd[1]: Started Music Player Daemon. Dec 21 10:52:25 volumio sudo[7961]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:25 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:25 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 21 10:52:25 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:25 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:28 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 21 10:52:28 volumio volumio[7322]: info: CoreStateMachine::getcurrentVolume Dec 21 10:52:28 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:28 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:28 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:28 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:28 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:28 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:28 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:28 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:28 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:28 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:28 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:28 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:28 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:30 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:30 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:38 volumio kernel: usb 1-3: USB disconnect, device number 5 Dec 21 10:52:38 volumio volumio[7322]: info: Dec 21 10:52:38 volumio volumio[7322]: ---------------------------- USB Audio Device Detached Dec 21 10:52:38 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 21 10:52:38 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:38 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 21 10:52:39 volumio kernel: usb 1-3: new high-speed USB device number 6 using xhci_hcd Dec 21 10:52:39 volumio kernel: usb 1-3: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Dec 21 10:52:39 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 21 10:52:39 volumio kernel: usb 1-3: Product: Combo384 Amanero Dec 21 10:52:39 volumio kernel: usb 1-3: Manufacturer: Amanero Technologies Dec 21 10:52:39 volumio kernel: usb 1-3: SerialNumber: 413-001 Dec 21 10:52:39 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:39 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Dec 21 10:52:39 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:39 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Dec 21 10:52:39 volumio volumio[7322]: info: Dec 21 10:52:39 volumio volumio[7322]: ---------------------------- USB Audio Device Attached Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:52:39 volumio volumio[7322]: info: Preparing to save Alsa Options, stopping services first Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:39 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::volumioPause Dec 21 10:52:39 volumio volumio[7322]: info: CoreStateMachine::pause Dec 21 10:52:39 volumio volumio[7322]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:39 volumio volumio[7322]: info: Setting mixer PCM for card Combo384 Amanero Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 10:52:39 volumio volumio[7322]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 10:52:39 volumio volumio[7322]: info: Disabling external Volume Control Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 10:52:39 volumio volumio[7322]: info: Preparing to generate the ALSA configuration file Dec 21 10:52:39 volumio volumio[7322]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 21 10:52:39 volumio volumio[7322]: info: Reading ALSA contributions from plugins. Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 10:52:39 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:39 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:39 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:39 volumio volumio[7322]: info: Asound.conf file unchanged, so no further update is needed Dec 21 10:52:39 volumio volumio[7322]: info: Output device has changed, restarting MPD Dec 21 10:52:39 volumio sudo[8085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 10:52:39 volumio sudo[8085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:39 volumio volumio[7322]: info: Output device has changed, restarting Shairport Sync Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:39 volumio sudo[8085]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:39 volumio sudo[8088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 10:52:39 volumio sudo[8088]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:39 volumio systemd[1]: Stopping Music Player Daemon... Dec 21 10:52:39 volumio volumio[7322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 10:52:39 volumio volumio[7322]: info: MPD Permissions set Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio systemd[1]: mpd.service: Succeeded. Dec 21 10:52:39 volumio systemd[1]: Stopped Music Player Daemon. Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: Starting Shairport Sync Dec 21 10:52:39 volumio sudo[8101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 10:52:39 volumio sudo[8101]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:39 volumio systemd[1]: Starting Music Player Daemon... Dec 21 10:52:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 10:52:39 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 21 10:52:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:39 volumio sudo[8101]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:39 volumio volumio[7322]: info: Shairport-Sync Started Dec 21 10:52:39 volumio sudo[8093]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 10:52:39 volumio sudo[8093]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:39 volumio sudo[8093]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:39 volumio mpd[8107]: Dec 21 10:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 10:52:39 volumio systemd[1]: Started Music Player Daemon. Dec 21 10:52:39 volumio sudo[8088]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:39 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:39 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:40 volumio kernel: usb 1-3: USB disconnect, device number 6 Dec 21 10:52:40 volumio volumio[7322]: info: Dec 21 10:52:40 volumio volumio[7322]: ---------------------------- USB Audio Device Detached Dec 21 10:52:40 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 21 10:52:40 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:40 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 21 10:52:40 volumio kernel: usb 1-3: new high-speed USB device number 7 using xhci_hcd Dec 21 10:52:41 volumio kernel: usb 1-3: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Dec 21 10:52:41 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 21 10:52:41 volumio kernel: usb 1-3: Product: Combo384 Amanero Dec 21 10:52:41 volumio kernel: usb 1-3: Manufacturer: Amanero Technologies Dec 21 10:52:41 volumio kernel: usb 1-3: SerialNumber: 413-001 Dec 21 10:52:41 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:41 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Dec 21 10:52:41 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:41 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Dec 21 10:52:41 volumio volumio[7322]: info: Dec 21 10:52:41 volumio volumio[7322]: ---------------------------- USB Audio Device Attached Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:52:41 volumio volumio[7322]: info: Preparing to save Alsa Options, stopping services first Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:41 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::volumioPause Dec 21 10:52:41 volumio volumio[7322]: info: CoreStateMachine::pause Dec 21 10:52:41 volumio volumio[7322]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:41 volumio volumio[7322]: info: Setting mixer PCM for card Combo384 Amanero Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 10:52:41 volumio volumio[7322]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 10:52:41 volumio volumio[7322]: info: Disabling external Volume Control Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 10:52:41 volumio volumio[7322]: info: Preparing to generate the ALSA configuration file Dec 21 10:52:41 volumio volumio[7322]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 21 10:52:41 volumio volumio[7322]: info: Reading ALSA contributions from plugins. Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 10:52:41 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:41 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:41 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:41 volumio volumio[7322]: info: Asound.conf file unchanged, so no further update is needed Dec 21 10:52:41 volumio volumio[7322]: info: Output device has changed, restarting MPD Dec 21 10:52:41 volumio sudo[8149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 10:52:41 volumio sudo[8149]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:41 volumio volumio[7322]: info: Output device has changed, restarting Shairport Sync Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:41 volumio sudo[8149]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:41 volumio sudo[8152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 10:52:41 volumio sudo[8152]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:41 volumio systemd[1]: Stopping Music Player Daemon... Dec 21 10:52:41 volumio volumio[7322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 10:52:41 volumio volumio[7322]: info: MPD Permissions set Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio systemd[1]: mpd.service: Succeeded. Dec 21 10:52:41 volumio systemd[1]: Stopped Music Player Daemon. Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: Starting Shairport Sync Dec 21 10:52:41 volumio sudo[8165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 10:52:41 volumio sudo[8165]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:41 volumio systemd[1]: Starting Music Player Daemon... Dec 21 10:52:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 10:52:41 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 21 10:52:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:41 volumio sudo[8165]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:41 volumio volumio[7322]: info: Shairport-Sync Started Dec 21 10:52:41 volumio sudo[8157]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 10:52:41 volumio sudo[8157]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:41 volumio sudo[8157]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:41 volumio mpd[8171]: Dec 21 10:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 10:52:41 volumio systemd[1]: Started Music Player Daemon. Dec 21 10:52:41 volumio sudo[8152]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:41 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:41 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:41 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:42 volumio kernel: usb 1-3: USB disconnect, device number 7 Dec 21 10:52:42 volumio volumio[7322]: info: Dec 21 10:52:42 volumio volumio[7322]: ---------------------------- USB Audio Device Detached Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 21 10:52:42 volumio kernel: usb 1-3: new high-speed USB device number 8 using xhci_hcd Dec 21 10:52:42 volumio kernel: usb 1-3: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Dec 21 10:52:42 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 21 10:52:42 volumio kernel: usb 1-3: Product: Combo384 Amanero Dec 21 10:52:42 volumio kernel: usb 1-3: Manufacturer: Amanero Technologies Dec 21 10:52:42 volumio kernel: usb 1-3: SerialNumber: 413-001 Dec 21 10:52:42 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:42 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Dec 21 10:52:42 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:42 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Dec 21 10:52:42 volumio volumio[7322]: info: Dec 21 10:52:42 volumio volumio[7322]: ---------------------------- USB Audio Device Attached Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:52:42 volumio volumio[7322]: info: Preparing to save Alsa Options, stopping services first Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:42 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::volumioPause Dec 21 10:52:42 volumio volumio[7322]: info: CoreStateMachine::pause Dec 21 10:52:42 volumio volumio[7322]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:42 volumio volumio[7322]: info: Setting mixer PCM for card Combo384 Amanero Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 10:52:42 volumio volumio[7322]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 10:52:42 volumio volumio[7322]: info: Disabling external Volume Control Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 10:52:42 volumio volumio[7322]: info: Preparing to generate the ALSA configuration file Dec 21 10:52:42 volumio volumio[7322]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 21 10:52:42 volumio volumio[7322]: info: Reading ALSA contributions from plugins. Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 10:52:42 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:42 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:42 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:42 volumio volumio[7322]: info: Asound.conf file unchanged, so no further update is needed Dec 21 10:52:42 volumio volumio[7322]: info: Output device has changed, restarting MPD Dec 21 10:52:42 volumio sudo[8205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 10:52:42 volumio sudo[8205]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:42 volumio volumio[7322]: info: Output device has changed, restarting Shairport Sync Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:42 volumio sudo[8205]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:42 volumio sudo[8207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 10:52:42 volumio sudo[8207]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:42 volumio systemd[1]: Stopping Music Player Daemon... Dec 21 10:52:42 volumio volumio[7322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 10:52:42 volumio volumio[7322]: info: MPD Permissions set Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio systemd[1]: mpd.service: Succeeded. Dec 21 10:52:42 volumio systemd[1]: Stopped Music Player Daemon. Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 10:52:42 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:42 volumio volumio[7322]: info: Starting Shairport Sync Dec 21 10:52:42 volumio sudo[8221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 10:52:43 volumio kernel: usb 1-3: USB disconnect, device number 8 Dec 21 10:52:42 volumio sudo[8221]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:43 volumio systemd[1]: Starting Music Player Daemon... Dec 21 10:52:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 10:52:43 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 21 10:52:43 volumio volumio[7322]: info: Dec 21 10:52:43 volumio volumio[7322]: ---------------------------- USB Audio Device Detached Dec 21 10:52:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:43 volumio sudo[8221]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 21 10:52:43 volumio volumio[7322]: info: Shairport-Sync Started Dec 21 10:52:43 volumio sudo[8213]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 10:52:43 volumio sudo[8213]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:43 volumio sudo[8213]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio mpd[8231]: Dec 21 10:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 10:52:43 volumio systemd[1]: Started Music Player Daemon. Dec 21 10:52:43 volumio sudo[8207]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:43 volumio kernel: usb 1-3: new high-speed USB device number 9 using xhci_hcd Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 21 10:52:43 volumio volumio[7322]: info: CoreStateMachine::getcurrentVolume Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:43 volumio volumio[7322]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 21 10:52:43 volumio volumio[7322]: info: VolumeController:: Volume=undefined Mute =false Dec 21 10:52:43 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:43 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:43 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:43 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:43 volumio volumio[7322]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 21 10:52:43 volumio volumio[7322]: info: VolumeController:: Volume=undefined Mute =false Dec 21 10:52:43 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:43 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 21 10:52:43 volumio kernel: usb 1-3: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Dec 21 10:52:43 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 21 10:52:43 volumio kernel: usb 1-3: Product: Combo384 Amanero Dec 21 10:52:43 volumio kernel: usb 1-3: Manufacturer: Amanero Technologies Dec 21 10:52:43 volumio kernel: usb 1-3: SerialNumber: 413-001 Dec 21 10:52:43 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:43 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Dec 21 10:52:43 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Dec 21 10:52:43 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: Dec 21 10:52:43 volumio volumio[7322]: ---------------------------- USB Audio Device Attached Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:52:43 volumio volumio[7322]: info: Preparing to save Alsa Options, stopping services first Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:43 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioPause Dec 21 10:52:43 volumio volumio[7322]: info: CoreStateMachine::pause Dec 21 10:52:43 volumio volumio[7322]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:43 volumio volumio[7322]: info: Setting mixer PCM for card Combo384 Amanero Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 21 10:52:43 volumio volumio[7322]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 21 10:52:43 volumio volumio[7322]: info: Disabling external Volume Control Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 10:52:43 volumio volumio[7322]: info: Preparing to generate the ALSA configuration file Dec 21 10:52:43 volumio volumio[7322]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 21 10:52:43 volumio volumio[7322]: info: Reading ALSA contributions from plugins. Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 10:52:43 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:43 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:43 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:43 volumio volumio[7322]: info: Asound.conf file unchanged, so no further update is needed Dec 21 10:52:43 volumio volumio[7322]: info: Output device has changed, restarting MPD Dec 21 10:52:43 volumio sudo[8261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 10:52:43 volumio sudo[8261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:43 volumio volumio[7322]: info: Output device has changed, restarting Shairport Sync Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:43 volumio sudo[8261]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio sudo[8264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 10:52:43 volumio sudo[8264]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:43 volumio systemd[1]: Stopping Music Player Daemon... Dec 21 10:52:43 volumio volumio[7322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 10:52:43 volumio volumio[7322]: info: MPD Permissions set Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio systemd[1]: mpd.service: Succeeded. Dec 21 10:52:43 volumio systemd[1]: Stopped Music Player Daemon. Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 21 10:52:43 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:43 volumio volumio[7322]: info: Starting Shairport Sync Dec 21 10:52:43 volumio sudo[8277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 10:52:43 volumio sudo[8277]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:43 volumio systemd[1]: Starting Music Player Daemon... Dec 21 10:52:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 10:52:43 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 21 10:52:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 10:52:43 volumio sudo[8277]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio volumio[7322]: info: Shairport-Sync Started Dec 21 10:52:43 volumio sudo[8270]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 10:52:43 volumio sudo[8270]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 10:52:43 volumio sudo[8270]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio mpd[8283]: Dec 21 10:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 10:52:43 volumio systemd[1]: Started Music Player Daemon. Dec 21 10:52:43 volumio sudo[8264]: pam_unix(sudo:session): session closed for user root Dec 21 10:52:43 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:44 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 21 10:52:44 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:44 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:52:45 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 21 10:52:45 volumio volumio[7322]: info: CoreStateMachine::getcurrentVolume Dec 21 10:52:45 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:45 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:45 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:45 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:45 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:45 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:45 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:45 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:45 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:45 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:45 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:45 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:45 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:46 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 21 10:52:46 volumio volumio[7322]: info: CoreStateMachine::getcurrentVolume Dec 21 10:52:46 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:46 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:46 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:46 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:46 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:46 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:46 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:46 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:46 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:46 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:46 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:46 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:46 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:47 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 21 10:52:47 volumio volumio[7322]: info: CoreStateMachine::getcurrentVolume Dec 21 10:52:47 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:47 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:47 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:47 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:47 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:47 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:47 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:47 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:47 volumio volumio[7322]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 10:52:47 volumio volumio[7322]: info: VolumeController:: Volume=46 Mute =false Dec 21 10:52:47 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:47 volumio volumio[7322]: info: CorePlayQueue::getTrack 4 Dec 21 10:52:47 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:49 volumio volumio[7322]: info: CoreCommandRouter::volumioNext Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::next Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::stop Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::play index undefined Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:52:49 volumio volumio[7322]: info: CorePlayQueue::getTrack 5 Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::startPlaybackTimer Dec 21 10:52:49 volumio volumio[7322]: info: CorePlayQueue::getTrack 5 Dec 21 10:52:49 volumio volumio[7322]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐/M4 DSD 发烧人声 经典流行 (500G)/01 童丽 DSD 315首/童丽 浪子心声.dsf Dec 21 10:52:49 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:52:49 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:49 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:49 volumio volumio[7322]: info: sendMpdCommand stop took 1 milliseconds Dec 21 10:52:49 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 10:52:49 volumio volumio[7322]: info: Dec 21 10:52:49 volumio volumio[7322]: ---------------------------- MPD announces system playlist update Dec 21 10:52:49 volumio volumio[7322]: info: Ignoring MPD Status Update Dec 21 10:52:49 volumio volumio[7322]: info: sendMpdCommand clear took 0 milliseconds Dec 21 10:52:49 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐/M4 DSD 发烧人声 经典流行 (500G)/01 童丽 DSD 315首/童丽 浪子心声.dsf" Dec 21 10:52:49 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:49 volumio volumio[7322]: info: ------------------------------ 1ms Dec 21 10:52:50 volumio volumio[7322]: info: CoreCommandRouter::volumioNext Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::next Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::stop Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::play index undefined Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:52:50 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::startPlaybackTimer Dec 21 10:52:50 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:50 volumio volumio[7322]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐/M4 DSD 发烧人声 经典流行 (500G)/01 童丽 DSD 315首/童丽 一封情书.dsf Dec 21 10:52:50 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:52:50 volumio volumio[7322]: info: CoreStateMachine::updateTrackBlock Dec 21 10:52:50 volumio volumio[7322]: info: CorePlayQueue::getTrackBlock Dec 21 10:52:50 volumio volumio[7322]: info: sendMpdCommand stop took 1 milliseconds Dec 21 10:52:50 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 10:52:50 volumio volumio[7322]: info: Dec 21 10:52:50 volumio volumio[7322]: ---------------------------- MPD announces system playlist update Dec 21 10:52:50 volumio volumio[7322]: info: Ignoring MPD Status Update Dec 21 10:52:50 volumio volumio[7322]: info: sendMpdCommand clear took 0 milliseconds Dec 21 10:52:50 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐/M4 DSD 发烧人声 经典流行 (500G)/01 童丽 DSD 315首/童丽 一封情书.dsf" Dec 21 10:52:50 volumio volumio[7322]: error: updateQueue error: null Dec 21 10:52:50 volumio volumio[7322]: info: ------------------------------ 1ms Dec 21 10:52:52 volumio volumio[7322]: info: CoreCommandRouter::volumioGetQueue Dec 21 10:52:52 volumio volumio[7322]: info: CoreStateMachine::getQueue Dec 21 10:52:52 volumio volumio[7322]: info: CorePlayQueue::getQueue Dec 21 10:52:52 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:52:52 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:53 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 21 10:52:53 volumio volumio[7322]: info: CURURI: music-library Dec 21 10:52:53 volumio volumio[7322]: info: Preload queue cleared Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 10:52:57 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand update Dec 21 10:52:57 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:52:57 volumio volumio[7322]: info: Dec 21 10:52:57 volumio volumio[7322]: ---------------------------- MPD announces state update: update Dec 21 10:52:57 volumio volumio[7322]: info: sendMpdCommand update took 1 milliseconds Dec 21 10:52:57 volumio volumio[7322]: info: ControllerMpd::getState Dec 21 10:52:57 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:52:57 volumio volumio[7322]: info: sendMpdCommand status took 1 milliseconds Dec 21 10:52:57 volumio volumio[7322]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:52:57 volumio volumio[7322]: verbose: ControllerMpd::parseState Dec 21 10:52:57 volumio volumio[7322]: info: Command Router : Notfying DB Updatetrue Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:52:57 volumio volumio[7322]: verbose: ControllerMpd::parseState Dec 21 10:52:57 volumio volumio[7322]: info: ControllerMpd::pushState Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::servicePushState Dec 21 10:52:57 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:57 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:57 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:57 volumio volumio[7322]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 10:52:57 volumio volumio[7322]: verbose: CURRENT POSITION 6 Dec 21 10:52:57 volumio volumio[7322]: info: CoreStateMachine::syncState stateService stop Dec 21 10:52:57 volumio volumio[7322]: info: CoreStateMachine::syncState currentStatus stop Dec 21 10:52:57 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:57 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:57 volumio volumio[7322]: info: No code Dec 21 10:52:57 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:52:57 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:52:57 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:52:57 volumio volumio[7322]: info: ------------------------------ 38ms Dec 21 10:53:00 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:53:00 volumio volumio[7322]: info: Dec 21 10:53:00 volumio volumio[7322]: ---------------------------- MPD announces state update: update Dec 21 10:53:00 volumio volumio[7322]: info: ControllerMpd::getState Dec 21 10:53:00 volumio volumio[7322]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:53:00 volumio volumio[7322]: info: sendMpdCommand status took 0 milliseconds Dec 21 10:53:00 volumio volumio[7322]: info: sendMpdCommand status took 0 milliseconds Dec 21 10:53:00 volumio volumio[7322]: verbose: ControllerMpd::parseState Dec 21 10:53:00 volumio volumio[7322]: info: Command Router : Notfying DB Updatefalse Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:53:00 volumio volumio[7322]: verbose: ControllerMpd::parseState Dec 21 10:53:00 volumio volumio[7322]: info: ControllerMpd::pushState Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::servicePushState Dec 21 10:53:00 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:53:00 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:53:00 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:53:00 volumio volumio[7322]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 10:53:00 volumio volumio[7322]: verbose: CURRENT POSITION 6 Dec 21 10:53:00 volumio volumio[7322]: info: CoreStateMachine::syncState stateService stop Dec 21 10:53:00 volumio volumio[7322]: info: CoreStateMachine::syncState currentStatus stop Dec 21 10:53:00 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:53:00 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:53:00 volumio volumio[7322]: info: No code Dec 21 10:53:00 volumio volumio[7322]: info: CoreStateMachine::pushState Dec 21 10:53:00 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:53:00 volumio volumio[7322]: info: CoreCommandRouter::volumioPushState Dec 21 10:53:00 volumio volumio[7322]: info: ------------------------------ 36ms Dec 21 10:53:01 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 21 10:53:01 volumio volumio[7322]: info: CURURI: music-library/USB Dec 21 10:53:01 volumio volumio[7322]: info: Preload queue cleared Dec 21 10:53:03 volumio volumio[7322]: info: CoreCommandRouter::volumioGetState Dec 21 10:53:03 volumio volumio[7322]: info: CorePlayQueue::getTrack 6 Dec 21 10:53:08 volumio volumio[7322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 21 10:53:08 volumio volumio[7322]: info: CURURI: music-library/USB/音乐盘 Dec 21 10:53:08 volumio volumio[7322]: info: Preload queue cleared Dec 21 10:53:08 volumio volumio[7322]: info: Preloading song: music-library/USB/音乐盘/32G音乐+视频测试专用歌曲.mp3 Dec 21 10:53:08 volumio volumio[7322]: info: Exploding uri music-library/USB/音乐盘/32G音乐+视频测试专用歌曲.mp3 in service mpd Dec 21 10:53:08 volumio volumio[7322]: info: ALBUMART /albumart?cacheid=381&web=//extralarge&path=%2Fmnt%2FUSB%2F%E9%9F%B3%E4%B9%90%E7%9B%98%2F32G%E9%9F%B3%E4%B9%90%2B%E8%A7%86%E9%A2%91%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E6%AD%8C%E6%9B%B2.mp3&metadata=false Dec 21 10:53:08 volumio volumio[7322]: info: URI /mnt/USB/音乐盘/32G音乐+视频测试专用歌曲.mp3 Dec 21 10:53:12 volumio volumio[7322]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 10:53:12 volumio volumio[7322]: Error: connect ETIMEDOUT 128.242.240.149:443 Dec 21 10:53:12 volumio volumio[7322]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 21 10:53:12 volumio volumio[7322]: errno: -110, Dec 21 10:53:12 volumio volumio[7322]: code: 'ETIMEDOUT', Dec 21 10:53:12 volumio volumio[7322]: syscall: 'connect', Dec 21 10:53:12 volumio volumio[7322]: address: '128.242.240.149', Dec 21 10:53:12 volumio volumio[7322]: port: 443 Dec 21 10:53:12 volumio volumio[7322]: } Dec 21 10:53:12 volumio volumio[7322]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 10:53:12 volumio sudo[8388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 10:52 Dec 21 10:53:12 volumio sudo[8388]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"