-- Logs begin at Thu 2025-06-26 23:28:40 CST, end at Thu 2025-06-26 23:41:02 CST. -- Jun 26 23:40:00 volumio ntpd[1055]: 162.159.200.1 local addr 192.168.101.39 -> Jun 26 23:40:24 volumio volumio[3067]: info: VolumeController::SetAlsaVolume70 Jun 26 23:40:24 volumio volumio[3067]: info: CoreStateMachine::pushState Jun 26 23:40:24 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 23:40:24 volumio volumio[3067]: info: CoreCommandRouter::volumioPushState Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadef0 trb-start 000000010beadf00 trb-end 000000010beadf00 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadf00 trb-start 000000010beadf10 trb-end 000000010beadf10 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadf10 trb-start 000000010beadf20 trb-end 000000010beadf20 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio kernel: usb 1-7: USB disconnect, device number 3 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadf20 trb-start 000000010beadf30 trb-end 000000010beadf30 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadf30 trb-start 000000010beadf40 trb-end 000000010beadf40 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadf40 trb-start 000000010beadf50 trb-end 000000010beadf50 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 9 comp_code 1 Jun 26 23:40:29 volumio kernel: xhci_hcd 0000:00:15.0: Looking for event-dma 000000010beadf50 trb-start 000000010beadf60 trb-end 000000010beadf60 seg-start 000000010bead000 seg-end 000000010beadff0 Jun 26 23:40:29 volumio thd[872]: Error reading device '/dev/input/event3' Jun 26 23:40:29 volumio volumio[3067]: info: Jun 26 23:40:29 volumio volumio[3067]: ---------------------------- USB Audio Device Detached Jun 26 23:40:29 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 26 23:40:29 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 23:40:29 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 26 23:40:32 volumio kernel: usb 1-7: new high-speed USB device number 4 using xhci_hcd Jun 26 23:40:32 volumio kernel: usb 1-7: New USB device found, idVendor=22e1, idProduct=ca01, bcdDevice= 1.01 Jun 26 23:40:32 volumio kernel: usb 1-7: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 26 23:40:32 volumio kernel: usb 1-7: Product: Serenade DSD Jun 26 23:40:32 volumio kernel: usb 1-7: Manufacturer: TempoTec Corp. Jun 26 23:40:32 volumio kernel: usb 1-7: 1:3 : unsupported format bits 0x100000000 Jun 26 23:40:32 volumio kernel: input: TempoTec Corp. Serenade DSD as /devices/pci0000:00/0000:00:15.0/usb1/1-7/1-7:1.2/0003:22E1:CA01.0002/input/input12 Jun 26 23:40:32 volumio kernel: hid-generic 0003:22E1:CA01.0002: input,hidraw0: USB HID v1.00 Device [TempoTec Corp. Serenade DSD] on usb-0000:00:15.0-7/input2 Jun 26 23:40:32 volumio volumio[3067]: info: Jun 26 23:40:32 volumio volumio[3067]: ---------------------------- USB Audio Device Attached Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::Close All Modals sent Jun 26 23:40:32 volumio volumio[3067]: info: Preparing to save Alsa Options, stopping services first Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::volumioGetState Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::volumioPause Jun 26 23:40:32 volumio volumio[3067]: info: CoreStateMachine::pause Jun 26 23:40:32 volumio volumio[3067]: info: CoreStateMachine::stPlaybackTimer Jun 26 23:40:32 volumio volumio[3067]: info: CoreStateMachine::servicePause Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::servicePause Jun 26 23:40:32 volumio volumio[3067]: info: Airplay Pause with DBUS Call Jun 26 23:40:32 volumio volumio[3067]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Serenade DSD","alsacard":"DSD"},"i2s":false} Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 23:40:32 volumio systemd-udevd[3415]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 23:40:32 volumio volumio[3067]: info: Setting default mixerSoftMaster for Softvolume device Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 26 23:40:32 volumio volumio[3067]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 26 23:40:32 volumio volumio[3067]: info: Disabling external Volume Control Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 23:40:32 volumio volumio[3067]: info: Enable softmixer device for audio device undefined Jun 26 23:40:32 volumio volumio[3067]: info: Error Airplay DBUS Call Pause: Error: Command failed: /usr/bin/dbus-send --system --print-reply --type=method_call --dest=org.gnome.ShairportSync '/org/gnome/ShairportSync' org.gnome.ShairportSync.RemoteControl.Pause Jun 26 23:40:32 volumio volumio[3067]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Jun 26 23:40:32 volumio volumio[3067]: info: CoreStateMachine::pushState Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::volumioPushState Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 26 23:40:32 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 23:40:32 volumio volumio[3067]: info: Software Volume ALSA configuration written Jun 26 23:40:32 volumio volumio[3067]: info: Preparing to generate the ALSA configuration file Jun 26 23:40:33 volumio volumio[3067]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 26 23:40:33 volumio volumio[3067]: info: Reading ALSA contributions from plugins. Jun 26 23:40:33 volumio volumio[3067]: info: Asound.conf file unchanged, so no further update is needed Jun 26 23:40:33 volumio volumio[3067]: info: Output device has changed, restarting MPD Jun 26 23:40:33 volumio sudo[3446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 23:40:33 volumio sudo[3446]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 23:40:33 volumio sudo[3446]: pam_unix(sudo:session): session closed for user root Jun 26 23:40:33 volumio volumio[3067]: info: Output device has changed, restarting Shairport Sync Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 23:40:33 volumio sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 23:40:33 volumio sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 23:40:33 volumio systemd[1]: Stopping Music Player Daemon... Jun 26 23:40:33 volumio volumio[3067]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 23:40:33 volumio volumio[3067]: info: MPD Permissions set Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio systemd[1]: mpd.service: Succeeded. Jun 26 23:40:33 volumio systemd[1]: Stopped Music Player Daemon. Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 23:40:33 volumio volumio[3067]: info: Starting Shairport Sync Jun 26 23:40:33 volumio systemd[1]: Starting Music Player Daemon... Jun 26 23:40:33 volumio sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 23:40:33 volumio sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 23:40:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 23:40:33 volumio shairport-sync[3279]: {"time":1750952345480,"response":"startAirplayPlayback Success"} Jun 26 23:40:33 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 26 23:40:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 23:40:33 volumio sudo[3458]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 23:40:33 volumio sudo[3458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 23:40:33 volumio sudo[3458]: pam_unix(sudo:session): session closed for user root Jun 26 23:40:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 23:40:33 volumio sudo[3462]: pam_unix(sudo:session): session closed for user root Jun 26 23:40:33 volumio volumio[3067]: info: Shairport-Sync Started Jun 26 23:40:33 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 26 23:40:33 volumio mpd[3466]: Jun 26 23:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 23:40:34 volumio systemd[1]: Started Music Player Daemon. Jun 26 23:40:34 volumio sudo[3449]: pam_unix(sudo:session): session closed for user root Jun 26 23:40:34 volumio volumio[3067]: error: updateQueue error: null Jun 26 23:40:36 volumio volumio[3067]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 26 23:40:36 volumio volumio[3067]: info: CoreStateMachine::getcurrentVolume Jun 26 23:40:36 volumio volumio[3067]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 23:40:36 volumio volumio[3067]: info: CoreStateMachine::pushState Jun 26 23:40:36 volumio volumio[3067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 23:40:36 volumio volumio[3067]: info: CoreCommandRouter::volumioPushState Jun 26 23:40:36 volumio volumio[3067]: info: CoreStateMachine::updateTrackBlock Jun 26 23:40:36 volumio volumio[3067]: info: CorePlayQueue::getTrackBlock Jun 26 23:40:36 volumio volumio[3067]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 23:40:36 volumio volumio[3067]: info: CoreStateMachine::pushState Jun 26 23:40:36 volumio volumio[3067]: info: CoreCommandRouter::volumioPushState Jun 26 23:41:02 volumio volumio[3067]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 23:41:02 volumio volumio[3067]: Error: connect ETIMEDOUT 203.111.254.117:443 Jun 26 23:41:02 volumio volumio[3067]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 26 23:41:02 volumio volumio[3067]: errno: -110, Jun 26 23:41:02 volumio volumio[3067]: code: 'ETIMEDOUT', Jun 26 23:41:02 volumio volumio[3067]: syscall: 'connect', Jun 26 23:41:02 volumio volumio[3067]: address: '203.111.254.117', Jun 26 23:41:02 volumio volumio[3067]: port: 443 Jun 26 23:41:02 volumio volumio[3067]: } Jun 26 23:41:02 volumio volumio[3067]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 23:41:02 volumio sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 23:40 Jun 26 23:41:02 volumio sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"