Oct 28 03:18:03 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:18:12 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:18:24 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:18:34 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:18:49 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:19:01 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:19:17 volumio kernel: usb 3-1: USB disconnect, device number 16 Oct 28 03:19:18 volumio kernel: usb 3-1: new high-speed USB device number 17 using xhci-hcd Oct 28 03:19:18 volumio kernel: usb 3-1: New USB device found, idVendor=262a, idProduct=13de, bcdDevice= 0.01 Oct 28 03:19:18 volumio kernel: usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 28 03:19:18 volumio kernel: usb 3-1: Product: ZDC-205A-SG Oct 28 03:19:18 volumio kernel: usb 3-1: Manufacturer: Covia Inc. Oct 28 03:19:18 volumio kernel: hid-generic 0003:262A:13DE.0010: No inputs registered, leaving Oct 28 03:19:18 volumio kernel: hid-generic 0003:262A:13DE.0010: hidraw0: USB HID v1.00 Device [Covia Inc. ZDC-205A-SG] on usb-xhci-hcd.1-1/input0 Oct 28 03:19:18 volumio volumio[18707]: info: Oct 28 03:19:18 volumio volumio[18707]: ---------------------------- USB Audio Device Detached Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 28 03:19:18 volumio (udev-worker)[20065]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Oct 28 03:19:18 volumio volumio[18707]: info: Oct 28 03:19:18 volumio volumio[18707]: ---------------------------- USB Audio Device Attached Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::Close All Modals sent Oct 28 03:19:18 volumio volumio[18707]: info: Preparing to save Alsa Options, stopping services first Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::volumioGetState Oct 28 03:19:18 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::volumioPause Oct 28 03:19:18 volumio volumio[18707]: info: CoreStateMachine::pause Oct 28 03:19:18 volumio volumio[18707]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"ZDC-205A-SG","alsacard":"ZDC205ASG"},"i2s":false} Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 03:19:18 volumio volumio[18707]: info: Setting mixer PCM for card ZDC-205A-SG Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 28 03:19:18 volumio volumio[18707]: info: Updating Volume Controller Parameters: Device: 5 Name: ZDC-205A-SG Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 28 03:19:18 volumio volumio[18707]: info: Disabling external Volume Control Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 03:19:18 volumio volumio[18707]: info: Preparing to generate the ALSA configuration file Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 28 03:19:18 volumio volumio[18707]: info: Asound.conf file unchanged, so no further update is needed Oct 28 03:19:18 volumio volumio[18707]: info: Output device has changed, restarting MPD Oct 28 03:19:18 volumio volumio[18707]: info: Output device has changed, restarting Shairport Sync Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 03:19:18 volumio sudo[20104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 28 03:19:18 volumio sudo[20104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 03:19:18 volumio sudo[20104]: pam_unix(sudo:session): session closed for user root Oct 28 03:19:18 volumio volumio[18707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 03:19:18 volumio volumio[18707]: info: VolumeController:: Volume=33 Mute =false Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::volumioGetState Oct 28 03:19:18 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:18 volumio volumio[18707]: info: CoreStateMachine::pushState Oct 28 03:19:18 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::volumioPushState Oct 28 03:19:18 volumio sudo[20108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 28 03:19:18 volumio sudo[20108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 03:19:18 volumio volumio[18707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 28 03:19:18 volumio volumio[18707]: info: MPD Permissions set Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::volumioGetState Oct 28 03:19:18 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:18 volumio volumio[18707]: info: Starting Shairport Sync Oct 28 03:19:18 volumio sudo[20121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 03:19:18 volumio sudo[20121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 03:19:18 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 28 03:19:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 28 03:19:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 28 03:19:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 28 03:19:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 28 03:19:18 volumio volumio[18707]: error: [ytcr] Error connecting MPD: Oct 28 03:19:18 volumio volumio[18707]: (Error) read ECONNRESET Oct 28 03:19:18 volumio volumio[18707]: Error: read ECONNRESET Oct 28 03:19:18 volumio volumio[18707]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) Oct 28 03:19:18 volumio volumio[18707]: Retrying in 5 seconds... Oct 28 03:19:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 28 03:19:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 28 03:19:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 28 03:19:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 28 03:19:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 28 03:19:18 volumio systemd[1]: shairport-sync.service: Consumed 1.848s CPU time. Oct 28 03:19:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 28 03:19:18 volumio sudo[20121]: pam_unix(sudo:session): session closed for user root Oct 28 03:19:18 volumio volumio[18707]: info: Shairport-Sync Started Oct 28 03:19:18 volumio sudo[20124]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 28 03:19:18 volumio sudo[20124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 28 03:19:18 volumio sudo[20124]: pam_unix(sudo:session): session closed for user root Oct 28 03:19:18 volumio volumio[18707]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 28 03:19:18 volumio mpd[20141]: 2025-10-28T03:19:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 28 03:19:18 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 28 03:19:18 volumio sudo[20108]: pam_unix(sudo:session): session closed for user root Oct 28 03:19:18 volumio volumio[18707]: error: updateQueue error: null Oct 28 03:19:21 volumio sudo[20147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 28 03:19:21 volumio sudo[20147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 03:19:21 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 28 03:19:21 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 28 03:19:21 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 28 03:19:21 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 28 03:19:21 volumio sudo[20147]: pam_unix(sudo:session): session closed for user root Oct 28 03:19:21 volumio mpd_monitor.sh[20149]: MPD Monitor Service: Starting MPD Monitor Service Oct 28 03:19:21 volumio volumio[18707]: info: Successfully started MPD Monitor Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 28 03:19:22 volumio volumio[18707]: info: CoreStateMachine::getcurrentVolume Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 03:19:22 volumio volumio[18707]: info: VolumeController:: Volume=33 Mute =false Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioGetState Oct 28 03:19:22 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:22 volumio volumio[18707]: info: CoreStateMachine::pushState Oct 28 03:19:22 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioPushState Oct 28 03:19:22 volumio volumio[18707]: info: CoreStateMachine::updateTrackBlock Oct 28 03:19:22 volumio volumio[18707]: info: CorePlayQueue::getTrackBlock Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 03:19:22 volumio volumio[18707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 28 03:19:22 volumio volumio[18707]: info: VolumeController:: Volume=33 Mute =false Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioGetState Oct 28 03:19:22 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:22 volumio volumio[18707]: info: CoreStateMachine::pushState Oct 28 03:19:22 volumio volumio[18707]: info: CorePlayQueue::getTrack 0 Oct 28 03:19:22 volumio volumio[18707]: info: CoreCommandRouter::volumioPushState Oct 28 03:19:22 volumio volumio[18707]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 28 03:19:41 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:19:44 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:19:50 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:19:55 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 28 03:19:56 volumio go-librespot[19122]: time="2025-10-28T03:19:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Oct 28 03:19:56 volumio volumio[18707]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Oct 28 03:19:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 03:19:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 03:19:56 volumio volumio[18707]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 03:19:56 volumio volumio[18707]: Error: socket hang up Oct 28 03:19:56 volumio volumio[18707]: at connResetException (node:internal/errors:720:14) Oct 28 03:19:56 volumio volumio[18707]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 28 03:19:56 volumio volumio[18707]: at Socket.emit (node:events:526:35) Oct 28 03:19:56 volumio volumio[18707]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 28 03:19:56 volumio volumio[18707]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 28 03:19:56 volumio volumio[18707]: code: 'ECONNRESET', Oct 28 03:19:56 volumio volumio[18707]: response: undefined Oct 28 03:19:56 volumio volumio[18707]: } Oct 28 03:19:56 volumio volumio[18707]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 03:19:57 volumio sudo[20233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 03:18' Oct 28 03:19:57 volumio sudo[20233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"