-- Logs begin at Sun 2025-03-09 08:30:00 CST, end at Sun 2025-03-09 08:32:31 CST. -- Mar 09 08:31:10 volumio kernel: usb 1-2: USB disconnect, device number 2 Mar 09 08:31:10 volumio kernel: usb 1-2.2: USB disconnect, device number 4 Mar 09 08:31:11 volumio thd[721]: Error reading device '/dev/input/event3' Mar 09 08:31:11 volumio thd[721]: Error reading device '/dev/input/event4' Mar 09 08:31:11 volumio thd[721]: Error reading device '/dev/input/event5' Mar 09 08:31:11 volumio thd[721]: Error reading device '/dev/input/event6' Mar 09 08:31:11 volumio kernel: usb 1-2: new high-speed USB device number 5 using xhci_hcd Mar 09 08:31:11 volumio kernel: usb 1-2: New USB device found, idVendor=214b, idProduct=7250, bcdDevice= 1.00 Mar 09 08:31:11 volumio kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 09 08:31:11 volumio kernel: usb 1-2: Product: USB2.0 HUB Mar 09 08:31:11 volumio kernel: hub 1-2:1.0: USB hub found Mar 09 08:31:11 volumio kernel: hub 1-2:1.0: 4 ports detected Mar 09 08:31:11 volumio sudo[2221]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:11 volumio kernel: usb 1-1: new high-speed USB device number 6 using xhci_hcd Mar 09 08:31:12 volumio kernel: usb 1-1: New USB device found, idVendor=1a40, idProduct=0201, bcdDevice= 1.00 Mar 09 08:31:12 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 09 08:31:12 volumio kernel: usb 1-1: Product: USB 2.0 Hub [MTT] Mar 09 08:31:12 volumio kernel: hub 1-1:1.0: USB hub found Mar 09 08:31:12 volumio kernel: hub 1-1:1.0: 7 ports detected Mar 09 08:31:12 volumio kernel: usb 1-2.2: new low-speed USB device number 7 using xhci_hcd Mar 09 08:31:12 volumio kernel: usb 1-2.2: New USB device found, idVendor=6901, idProduct=1224, bcdDevice= 3.03 Mar 09 08:31:12 volumio kernel: usb 1-2.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Mar 09 08:31:12 volumio kernel: input: HID 6901:1224 as /devices/pci0000:00/0000:00:15.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:6901:1224.0004/input/input18 Mar 09 08:31:12 volumio kernel: hid-generic 0003:6901:1224.0004: input,hidraw1: USB HID v1.10 Keyboard [HID 6901:1224] on usb-0000:00:15.0-2.2/input0 Mar 09 08:31:12 volumio kernel: input: HID 6901:1224 Mouse as /devices/pci0000:00/0000:00:15.0/usb1/1-2/1-2.2/1-2.2:1.1/0003:6901:1224.0005/input/input19 Mar 09 08:31:12 volumio kernel: input: HID 6901:1224 Consumer Control as /devices/pci0000:00/0000:00:15.0/usb1/1-2/1-2.2/1-2.2:1.1/0003:6901:1224.0005/input/input20 Mar 09 08:31:12 volumio kernel: usb 1-1.1: new high-speed USB device number 8 using xhci_hcd Mar 09 08:31:12 volumio kernel: input: HID 6901:1224 System Control as /devices/pci0000:00/0000:00:15.0/usb1/1-2/1-2.2/1-2.2:1.1/0003:6901:1224.0005/input/input21 Mar 09 08:31:12 volumio kernel: hid-generic 0003:6901:1224.0005: input,hidraw2: USB HID v1.10 Mouse [HID 6901:1224] on usb-0000:00:15.0-2.2/input1 Mar 09 08:31:12 volumio systemd-udevd[2425]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 09 08:31:12 volumio systemd-logind[731]: Watching system buttons on /dev/input/event3 (HID 6901:1224) Mar 09 08:31:12 volumio systemd-udevd[2433]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 09 08:31:12 volumio systemd-udevd[2426]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 09 08:31:12 volumio kernel: usb 1-1.1: New USB device found, idVendor=0409, idProduct=005a, bcdDevice= 1.00 Mar 09 08:31:12 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Mar 09 08:31:12 volumio kernel: hub 1-1.1:1.0: USB hub found Mar 09 08:31:12 volumio kernel: hub 1-1.1:1.0: 4 ports detected Mar 09 08:31:12 volumio systemd-udevd[2441]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 09 08:31:12 volumio systemd-logind[731]: Watching system buttons on /dev/input/event6 (HID 6901:1224 System Control) Mar 09 08:31:12 volumio systemd-udevd[2435]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 09 08:31:12 volumio systemd-logind[731]: Watching system buttons on /dev/input/event5 (HID 6901:1224 Consumer Control) Mar 09 08:31:13 volumio kernel: hub 1-1.1:1.0: hub_ext_port_status failed (err = -71) Mar 09 08:31:14 volumio kernel: usb 1-1: USB disconnect, device number 6 Mar 09 08:31:14 volumio kernel: usb 1-1.1: Failed to suspend device, error -71 Mar 09 08:31:14 volumio kernel: usb 1-1.1: USB disconnect, device number 8 Mar 09 08:31:16 volumio kernel: usb 1-1: new high-speed USB device number 9 using xhci_hcd Mar 09 08:31:16 volumio kernel: usb 1-1: New USB device found, idVendor=1a40, idProduct=0201, bcdDevice= 1.00 Mar 09 08:31:16 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 09 08:31:16 volumio kernel: usb 1-1: Product: USB 2.0 Hub [MTT] Mar 09 08:31:16 volumio kernel: hub 1-1:1.0: USB hub found Mar 09 08:31:16 volumio kernel: hub 1-1:1.0: 7 ports detected Mar 09 08:31:17 volumio kernel: usb 1-1.1: new high-speed USB device number 10 using xhci_hcd Mar 09 08:31:17 volumio kernel: usb 1-1.1: New USB device found, idVendor=0409, idProduct=005a, bcdDevice= 1.00 Mar 09 08:31:17 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Mar 09 08:31:17 volumio kernel: hub 1-1.1:1.0: USB hub found Mar 09 08:31:17 volumio kernel: hub 1-1.1:1.0: 4 ports detected Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: new high-speed USB device number 11 using xhci_hcd Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: New USB device found, idVendor=16d0, idProduct=0a23, bcdDevice= 1.9b Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: Product: Combo384 Amanero Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: Manufacturer: Amanero Technologies Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: SerialNumber: 415-001 Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Mar 09 08:31:17 volumio kernel: usb 1-1.1.1: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Mar 09 08:31:17 volumio kernel: usbcore: registered new interface driver snd-usb-audio Mar 09 08:31:17 volumio volumio[1233]: info: Mar 09 08:31:17 volumio volumio[1233]: ---------------------------- USB Audio Device Attached Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::Close All Modals sent Mar 09 08:31:17 volumio volumio[1233]: info: Preparing to save Alsa Options, stopping services first Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:17 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::volumioPause Mar 09 08:31:17 volumio volumio[1233]: info: CoreStateMachine::pause Mar 09 08:31:17 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Mar 09 08:31:17 volumio volumio[1233]: info: CoreStateMachine::servicePause Mar 09 08:31:17 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::servicePause Mar 09 08:31:17 volumio volumio[1233]: info: ControllerMpd::pause Mar 09 08:31:17 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand pause Mar 09 08:31:17 volumio volumio[1233]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:17 volumio volumio[1233]: info: Setting mixer PCM for card Combo384 Amanero Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 09 08:31:17 volumio volumio[1233]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 09 08:31:17 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 09 08:31:17 volumio volumio[1233]: info: Disabling external Volume Control Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:31:18 volumio volumio[1233]: info: Preparing to generate the ALSA configuration file Mar 09 08:31:18 volumio volumio[1233]: info: The plugin alsa_controller has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 09 08:31:18 volumio volumio[1233]: info: Reading ALSA contributions from plugins. Mar 09 08:31:18 volumio volumio[1233]: info: Mar 09 08:31:18 volumio volumio[1233]: ---------------------------- MPD announces state update: player Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand pause took 153 milliseconds Mar 09 08:31:18 volumio volumio[1233]: info: ControllerMpd::getState Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Mar 09 08:31:18 volumio volumio[1233]: info: Mar 09 08:31:18 volumio volumio[1233]: ---------------------------- MPD announces state update: player Mar 09 08:31:18 volumio volumio[1233]: info: ControllerMpd::getState Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Mar 09 08:31:18 volumio volumio[1233]: info: Mar 09 08:31:18 volumio volumio[1233]: ---------------------------- MPD announces state update: player Mar 09 08:31:18 volumio volumio[1233]: info: ControllerMpd::getState Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand status took 20 milliseconds Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand status took 18 milliseconds Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand status took 17 milliseconds Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::parseState Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::parseState Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::parseState Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 09 08:31:18 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Mar 09 08:31:18 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Mar 09 08:31:18 volumio volumio[1233]: info: ControllerMpd::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":45760,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"685 Kbps","isStreaming":false,"title":"其實你不懂我的心","artist":"童安格","album":"其實你不懂我的心","uri":"NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 01.其實你不懂我的心.flac","trackType":"flac"} Mar 09 08:31:18 volumio volumio[1233]: verbose: CURRENT POSITION 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::syncState stateService pause Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus pause Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Mar 09 08:31:18 volumio volumio[1233]: info: ControllerMpd::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":45760,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"685 Kbps","isStreaming":false,"title":"其實你不懂我的心","artist":"童安格","album":"其實你不懂我的心","uri":"NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 01.其實你不懂我的心.flac","trackType":"flac"} Mar 09 08:31:18 volumio volumio[1233]: verbose: CURRENT POSITION 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::syncState stateService pause Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus pause Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Mar 09 08:31:18 volumio volumio[1233]: info: ControllerMpd::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":45760,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"685 Kbps","isStreaming":false,"title":"其實你不懂我的心","artist":"童安格","album":"其實你不懂我的心","uri":"NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 01.其實你不懂我的心.flac","trackType":"flac"} Mar 09 08:31:18 volumio volumio[1233]: verbose: CURRENT POSITION 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::syncState stateService pause Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus pause Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Mar 09 08:31:18 volumio volumio[1233]: info: ------------------------------ 55ms Mar 09 08:31:18 volumio volumio[1233]: info: ------------------------------ 54ms Mar 09 08:31:18 volumio volumio[1233]: info: ------------------------------ 53ms Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 09 08:31:18 volumio volumio[1233]: info: VolumeController:: Volume=100 Mute =false Mar 09 08:31:18 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:18 volumio volumio[1233]: info: Asound.conf file unchanged, so no further update is needed Mar 09 08:31:18 volumio volumio[1233]: info: Output device has changed, restarting MPD Mar 09 08:31:18 volumio sudo[2485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 08:31:18 volumio sudo[2485]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:18 volumio sudo[2485]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:18 volumio volumio[1233]: info: Output device has changed, restarting Shairport Sync Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:18 volumio sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 08:31:18 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:18 volumio systemd[1]: Stopping Music Player Daemon... Mar 09 08:31:18 volumio volumio[1233]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 08:31:18 volumio systemd[1]: mpd.service: Succeeded. Mar 09 08:31:18 volumio systemd[1]: Stopped Music Player Daemon. Mar 09 08:31:18 volumio volumio[1233]: info: MPD Permissions set Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio systemd[1]: Starting Music Player Daemon... Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:18 volumio volumio[1233]: info: Starting Shairport Sync Mar 09 08:31:18 volumio sudo[2493]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 08:31:18 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:18 volumio sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 09 08:31:18 volumio sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:18 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 09 08:31:18 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 09 08:31:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 09 08:31:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 09 08:31:18 volumio sudo[2501]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:18 volumio volumio[1233]: info: Shairport-Sync Started Mar 09 08:31:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 09 08:31:18 volumio mpd[2504]: Mar 09 08:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 08:31:18 volumio systemd[1]: Started Music Player Daemon. Mar 09 08:31:18 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:18 volumio volumio[1233]: error: updateQueue error: null Mar 09 08:31:19 volumio volumio[1233]: error: Upnp client error: Error: This socket has been ended by the other party Mar 09 08:31:20 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:20 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:22 volumio volumio[1233]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 09 08:31:22 volumio volumio[1233]: info: CoreStateMachine::getcurrentVolume Mar 09 08:31:22 volumio volumio[1233]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 08:31:22 volumio volumio[1233]: info: VolumeController:: Volume=100 Mute =false Mar 09 08:31:22 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:22 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:22 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:22 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Mar 09 08:31:22 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Mar 09 08:31:22 volumio volumio[1233]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 08:31:22 volumio volumio[1233]: info: VolumeController:: Volume=100 Mute =false Mar 09 08:31:22 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:22 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:22 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:29 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:29 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 08:31:31 volumio volumio[1233]: info: Discovery: Getting this device information Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:31 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 08:31:31 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.51 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:31 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 09 08:31:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 09 08:31:35 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay Mar 09 08:31:35 volumio volumio[1233]: info: CoreStateMachine::play index undefined Mar 09 08:31:35 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 08:31:35 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:35 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer Mar 09 08:31:35 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:35 volumio volumio[1233]: info: ControllerMpd::resume Mar 09 08:31:35 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Mar 09 08:31:35 volumio volumio[1233]: info: sendMpdCommand play took 1 milliseconds Mar 09 08:31:38 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:38 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:44 volumio sudo[2581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 08:31:44 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:44 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:44 volumio sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 08:31:44 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:44 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:44 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.51 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::volumioGetVisibleSources Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:44 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 09 08:31:44 volumio volumio[1233]: info: Received Get System Info Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 08:31:44 volumio volumio[1233]: info: Discovery: Getting this device information Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:44 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 08:31:44 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:44 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:45 volumio volumio[1233]: info: Listing playlists Mar 09 08:31:45 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 09 08:31:45 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 09 08:31:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 09 08:31:46 volumio volumio[1233]: info: Received Get System Info Mar 09 08:31:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 08:31:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 08:31:46 volumio volumio[1233]: info: Discovery: Getting this device information Mar 09 08:31:46 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 08:31:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 09 08:31:48 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:48 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 09 08:31:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 09 08:31:54 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 09 08:31:55 volumio volumio[1233]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 09 08:31:55 volumio volumio[1233]: info: Preparing to save Alsa Options, stopping services first Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPause Mar 09 08:31:55 volumio volumio[1233]: info: CoreStateMachine::pause Mar 09 08:31:55 volumio volumio[1233]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:55 volumio volumio[1233]: info: Setting mixer PCM for card Combo384 Amanero Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 09 08:31:55 volumio volumio[1233]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 09 08:31:55 volumio volumio[1233]: info: Disabling external Volume Control Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:31:55 volumio volumio[1233]: info: Preparing to generate the ALSA configuration file Mar 09 08:31:55 volumio volumio[1233]: info: The plugin alsa_controller has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 09 08:31:55 volumio volumio[1233]: info: Reading ALSA contributions from plugins. Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 09 08:31:55 volumio volumio[1233]: info: VolumeController:: Volume=100 Mute =false Mar 09 08:31:55 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:55 volumio volumio[1233]: info: Asound.conf file unchanged, so no further update is needed Mar 09 08:31:55 volumio volumio[1233]: info: Output device has changed, restarting MPD Mar 09 08:31:55 volumio sudo[2631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 08:31:55 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:55 volumio sudo[2631]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:55 volumio volumio[1233]: info: Output device has changed, restarting Shairport Sync Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:55 volumio sudo[2634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 08:31:55 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:55 volumio systemd[1]: Stopping Music Player Daemon... Mar 09 08:31:55 volumio volumio[1233]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 08:31:55 volumio volumio[1233]: info: MPD Permissions set Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio systemd[1]: mpd.service: Succeeded. Mar 09 08:31:55 volumio systemd[1]: Stopped Music Player Daemon. Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 09 08:31:55 volumio systemd[1]: Starting Music Player Daemon... Mar 09 08:31:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:31:55 volumio volumio[1233]: info: Starting Shairport Sync Mar 09 08:31:55 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 09 08:31:55 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 09 08:31:55 volumio sudo[2639]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 08:31:55 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:31:55 volumio sudo[2639]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:55 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 09 08:31:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 09 08:31:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 09 08:31:55 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:55 volumio volumio[1233]: info: Shairport-Sync Started Mar 09 08:31:56 volumio mpd[2651]: Mar 09 08:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 08:31:56 volumio systemd[1]: Started Music Player Daemon. Mar 09 08:31:56 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root Mar 09 08:31:56 volumio volumio[1233]: error: updateQueue error: null Mar 09 08:31:57 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:31:57 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:59 volumio volumio[1233]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 09 08:31:59 volumio volumio[1233]: info: CoreStateMachine::getcurrentVolume Mar 09 08:31:59 volumio volumio[1233]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 08:31:59 volumio volumio[1233]: info: VolumeController:: Volume=100 Mute =false Mar 09 08:31:59 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:59 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:59 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:31:59 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:31:59 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Mar 09 08:31:59 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Mar 09 08:31:59 volumio volumio[1233]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 08:31:59 volumio volumio[1233]: info: VolumeController:: Volume=100 Mute =false Mar 09 08:31:59 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:31:59 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:31:59 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::volumioNext Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::next Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::stop Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::serviceStop Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::serviceStop Mar 09 08:32:04 volumio volumio[1233]: info: ControllerMpd::stop Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand stop took 4 milliseconds Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::play index undefined Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 10.愛讓世界更美.flac Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand stop took 1 milliseconds Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 08:32:04 volumio volumio[1233]: info: Mar 09 08:32:04 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Mar 09 08:32:04 volumio volumio[1233]: info: Ignoring MPD Status Update Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand clear took 2 milliseconds Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 10.愛讓世界更美.flac" Mar 09 08:32:04 volumio volumio[1233]: error: updateQueue error: null Mar 09 08:32:04 volumio volumio[1233]: info: ------------------------------ 2ms Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand add "NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 10.愛讓世界更美.flac" took 1 milliseconds Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Mar 09 08:32:04 volumio volumio[1233]: info: Mar 09 08:32:04 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Mar 09 08:32:04 volumio volumio[1233]: info: Ignoring MPD Status Update Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand play took 2 milliseconds Mar 09 08:32:04 volumio volumio[1233]: info: ------------------------------ 4ms Mar 09 08:32:04 volumio volumio[1233]: info: Mar 09 08:32:04 volumio volumio[1233]: ---------------------------- MPD announces state update: player Mar 09 08:32:04 volumio volumio[1233]: info: ControllerMpd::getState Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Mar 09 08:32:04 volumio volumio[1233]: info: Mar 09 08:32:04 volumio volumio[1233]: ---------------------------- MPD announces state update: player Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand status took 58 milliseconds Mar 09 08:32:04 volumio volumio[1233]: info: ControllerMpd::getState Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::parseState Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::parseState Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Mar 09 08:32:04 volumio volumio[1233]: info: ControllerMpd::pushState Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:04 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愛讓世界更美","artist":"童安格","album":"其實你不懂我的心","uri":"NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 10.愛讓世界更美.flac","trackType":"flac"} Mar 09 08:32:04 volumio volumio[1233]: verbose: CURRENT POSITION 9 Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Mar 09 08:32:04 volumio volumio[1233]: info: ------------------------------ 67ms Mar 09 08:32:04 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 09 08:32:04 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Mar 09 08:32:04 volumio volumio[1233]: info: ControllerMpd::pushState Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:04 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"479 Kbps","isStreaming":false,"title":"愛讓世界更美","artist":"童安格","album":"其實你不懂我的心","uri":"NAS/music/台湾流行音乐200最佳专辑（1975～2005）/（021）童安格 - 其实你不懂我的心（1989）/童安格 - 10.愛讓世界更美.flac","trackType":"flac"} Mar 09 08:32:04 volumio volumio[1233]: verbose: CURRENT POSITION 9 Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Mar 09 08:32:04 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:32:04 volumio volumio[1233]: info: CoreStateMachine::pushState Mar 09 08:32:04 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:04 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Mar 09 08:32:04 volumio volumio[1233]: info: ------------------------------ 39ms Mar 09 08:32:06 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:32:06 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:06 volumio sudo[2735]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/chmod +x /run_peppymeter.sh Mar 09 08:32:06 volumio sudo[2735]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:32:06 volumio screensaver.sh[743]: chmod: cannot access '/run_peppymeter.sh': No such file or directory Mar 09 08:32:06 volumio sudo[2735]: pam_unix(sudo:session): session closed for user root Mar 09 08:32:06 volumio sudo[2737]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=./run_peppymeter.sh Mar 09 08:32:06 volumio sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:32:07 volumio volumio[1233]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 09 08:32:07 volumio volumio[1233]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 09 08:32:07 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Mar 09 08:32:07 volumio volumio[1233]: info: CorePlayQueue::getTrack 9 Mar 09 08:32:13 volumio volumio[1233]: info: CoreCommandRouter::volumioGetQueue Mar 09 08:32:13 volumio volumio[1233]: info: CoreStateMachine::getQueue Mar 09 08:32:13 volumio volumio[1233]: info: CorePlayQueue::getQueue Mar 09 08:32:14 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 08:32:14 volumio volumio[1233]: info: CURURI: music-library Mar 09 08:32:14 volumio volumio[1233]: info: Preload queue cleared Mar 09 08:32:17 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 08:32:17 volumio volumio[1233]: info: CURURI: music-library/NAS Mar 09 08:32:18 volumio volumio[1233]: info: Preload queue cleared Mar 09 08:32:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 08:32:18 volumio volumio[1233]: info: CURURI: music-library/NAS/music Mar 09 08:32:18 volumio volumio[1233]: info: Preload queue cleared Mar 09 08:32:24 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 08:32:24 volumio volumio[1233]: info: CURURI: music-library/NAS/music/[24B-192kHz] Mar 09 08:32:24 volumio volumio[1233]: info: Preload queue cleared Mar 09 08:32:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 08:32:29 volumio volumio[1233]: info: CURURI: music-library/NAS/music/[24B-192kHz]/192K发烧完整珍藏31套 Mar 09 08:32:29 volumio volumio[1233]: info: Preload queue cleared Mar 09 08:32:30 volumio volumio[1233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 08:32:30 volumio volumio[1233]: Error: connect ETIMEDOUT 108.160.167.158:443 Mar 09 08:32:30 volumio volumio[1233]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 09 08:32:30 volumio volumio[1233]: errno: -110, Mar 09 08:32:30 volumio volumio[1233]: code: 'ETIMEDOUT', Mar 09 08:32:30 volumio volumio[1233]: syscall: 'connect', Mar 09 08:32:30 volumio volumio[1233]: address: '108.160.167.158', Mar 09 08:32:30 volumio volumio[1233]: port: 443 Mar 09 08:32:30 volumio volumio[1233]: } Mar 09 08:32:30 volumio volumio[1233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 08:32:31 volumio sudo[2833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 08:31 Mar 09 08:32:31 volumio sudo[2833]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"