-- Logs begin at Tue 2026-05-26 08:14:52 UTC, end at Tue 2026-05-26 09:22:30 UTC. -- May 26 09:21:05 volumio kernel: Voltage normalised (0x00000000) May 26 09:21:25 volumio volumio[778]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 26 09:21:25 volumio sudo[7161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:25 volumio sudo[7161]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:21:25 volumio sudo[7161]: pam_unix(sudo:session): session closed for user root May 26 09:21:25 volumio sudo[7163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 09:21:25 volumio sudo[7163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:21:26 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 09:21:26 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:26 volumio volumio[778]: info: MPD Permissions set May 26 09:21:26 volumio systemd[1]: Stopping Music Player Daemon... May 26 09:21:26 volumio systemd[1]: Starting Music Player Daemon... May 26 09:21:26 volumio volumio[778]: error: Upnp client error: Error: This socket has been ended by the other party May 26 09:21:30 volumio systemd[1]: Started Music Player Daemon. May 26 09:21:30 volumio sudo[7163]: pam_unix(sudo:session): session closed for user root May 26 09:21:30 volumio volumio[778]: error: updateQueue error: null May 26 09:21:46 volumio volumio[778]: info: CorePlayQueue::getTrack 78 May 26 09:21:46 volumio volumio[778]: info: CorePlayQueue::getTrack 79 May 26 09:21:46 volumio volumio[778]: info: Prefetching next song May 26 09:21:46 volumio volumio[778]: info: DOING PREFETCH IN MPD May 26 09:21:46 volumio volumio[778]: verbose: ControllerMpd::sendMpdCommand add "USB/18D1-41D5/888/孙楠 - 是否爱过我.flac" May 26 09:21:46 volumio volumio[778]: info: May 26 09:21:46 volumio volumio[778]: ---------------------------- MPD announces system playlist update May 26 09:21:46 volumio volumio[778]: info: Ignoring MPD Status Update May 26 09:21:46 volumio volumio[778]: info: sendMpdCommand add "USB/18D1-41D5/888/孙楠 - 是否爱过我.flac" took 21 milliseconds May 26 09:21:46 volumio volumio[778]: verbose: ControllerMpd::sendMpdCommand consume 1 May 26 09:21:46 volumio volumio[778]: info: May 26 09:21:46 volumio volumio[778]: ---------------------------- MPD announces state update: options May 26 09:21:46 volumio volumio[778]: info: ------------------------------ 49ms May 26 09:21:46 volumio volumio[778]: info: sendMpdCommand consume 1 took 28 milliseconds May 26 09:21:47 volumio volumio[778]: info: ControllerMpd::getState May 26 09:21:47 volumio volumio[778]: verbose: ControllerMpd::sendMpdCommand status May 26 09:21:47 volumio volumio[778]: info: sendMpdCommand status took 108 milliseconds May 26 09:21:47 volumio volumio[778]: verbose: ControllerMpd::parseState May 26 09:21:47 volumio volumio[778]: info: ControllerMpd::pushState May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::servicePushState May 26 09:21:47 volumio volumio[778]: info: CoreStateMachine::pushState May 26 09:21:47 volumio volumio[778]: info: CorePlayQueue::getTrack 78 May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::volumioPushState May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 09:21:47 volumio volumio[778]: info: CorePlayQueue::getTrack 78 May 26 09:21:47 volumio volumio[778]: 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} May 26 09:21:47 volumio volumio[778]: verbose: CURRENT POSITION 78 May 26 09:21:47 volumio volumio[778]: info: CoreStateMachine::syncState stateService stop May 26 09:21:47 volumio volumio[778]: info: CoreStateMachine::syncState currentStatus play May 26 09:21:47 volumio volumio[778]: info: CoreStateMachine::pushState May 26 09:21:47 volumio volumio[778]: info: CorePlayQueue::getTrack 79 May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::volumioPushState May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 09:21:47 volumio volumio[778]: info: CoreStateMachine::pushState May 26 09:21:47 volumio volumio[778]: info: CorePlayQueue::getTrack 79 May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::volumioPushState May 26 09:21:47 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 09:21:47 volumio volumio[778]: info: ------------------------------ 346ms May 26 09:21:48 volumio volumio[778]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] May 26 09:21:48 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts May 26 09:21:48 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 26 09:21:49 volumio sudo[7193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio sudo[7193]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio sudo[7193]: pam_unix(sudo:session): session closed for user root May 26 09:21:49 volumio sudo[7195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 09:21:49 volumio sudo[7195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 09:21:49 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:21:49 volumio volumio[778]: info: MPD Permissions set May 26 09:21:49 volumio systemd[1]: Stopping Music Player Daemon... May 26 09:21:49 volumio systemd[1]: Starting Music Player Daemon... May 26 09:21:53 volumio systemd[1]: Started Music Player Daemon. May 26 09:21:53 volumio sudo[7195]: pam_unix(sudo:session): session closed for user root May 26 09:21:53 volumio volumio[778]: error: updateQueue error: null May 26 09:21:55 volumio kernel: usb 1-1.4: USB disconnect, device number 16 May 26 09:21:55 volumio volumio[778]: info: May 26 09:21:55 volumio volumio[778]: ---------------------------- USB Audio Device Detached May 26 09:21:55 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 26 09:21:55 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 09:21:56 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 26 09:21:57 volumio kernel: usb 1-1.4: new high-speed USB device number 17 using dwc_otg May 26 09:21:57 volumio kernel: usb 1-1.4: New USB device found, idVendor=152a, idProduct=887d, bcdDevice=cd.01 May 26 09:21:57 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 26 09:21:57 volumio kernel: usb 1-1.4: Product: Nowna Audio May 26 09:21:57 volumio kernel: usb 1-1.4: Manufacturer: Nowna May 26 09:21:57 volumio kernel: usb 1-1.4: 1:3 : unsupported format bits 0x100000000 May 26 09:21:57 volumio kernel: hid-generic 0003:152A:887D.000B: hiddev96,hidraw0: USB HID v1.10 Device [Nowna Nowna Audio] on usb-20980000.usb-1.4/input3 May 26 09:21:57 volumio volumio[778]: info: May 26 09:21:57 volumio volumio[778]: ---------------------------- USB Audio Device Attached May 26 09:21:57 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 26 09:21:58 volumio volumio[778]: info: CoreCommandRouter::Close All Modals sent May 26 09:21:58 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 09:21:58 volumio volumio[778]: info: Output device has changed, restarting MPD May 26 09:21:58 volumio volumio[778]: info: Output device has changed, restarting Shairport Sync May 26 09:21:58 volumio sudo[7238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 09:21:58 volumio sudo[7238]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:21:58 volumio sudo[7238]: pam_unix(sudo:session): session closed for user root May 26 09:21:58 volumio sudo[7240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 09:21:58 volumio sudo[7240]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:21:58 volumio systemd[1]: Stopping Music Player Daemon... May 26 09:21:58 volumio volumio[778]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 09:21:58 volumio systemd[1]: Starting Music Player Daemon... May 26 09:21:59 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 09:21:59 volumio volumio[778]: info: Device 5 does not have any Mixer Control Available, setting a softvol device May 26 09:22:00 volumio volumio[778]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 26 09:22:00 volumio volumio[778]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 May 26 09:22:00 volumio volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 09:22:00 volumio volumio[778]: info: CoreStateMachine::pushState May 26 09:22:00 volumio volumio[778]: info: CorePlayQueue::getTrack 79 May 26 09:22:00 volumio volumio[778]: info: CoreCommandRouter::volumioPushState May 26 09:22:00 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 09:22:01 volumio volumio[778]: info: MPD Permissions set May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 09:22:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 09:22:02 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 26 09:22:02 volumio volumio[778]: info: Starting Shairport Sync May 26 09:22:03 volumio volumio[778]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 26 09:22:03 volumio volumio[778]: info: CoreStateMachine::getcurrentVolume May 26 09:22:03 volumio volumio[778]: info: CoreCommandRouter::volumioRetrievevolume May 26 09:22:03 volumio volumio[778]: info: CoreStateMachine::pushState May 26 09:22:03 volumio volumio[778]: info: CorePlayQueue::getTrack 79 May 26 09:22:03 volumio volumio[778]: info: CoreCommandRouter::volumioPushState May 26 09:22:03 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 09:22:03 volumio sudo[7289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 09:22:03 volumio sudo[7289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:22:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 26 09:22:03 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 26 09:22:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 26 09:22:03 volumio sudo[7289]: pam_unix(sudo:session): session closed for user root May 26 09:22:04 volumio volumio[778]: info: Shairport-Sync Started May 26 09:22:06 volumio systemd[1]: Started Music Player Daemon. May 26 09:22:06 volumio sudo[7240]: pam_unix(sudo:session): session closed for user root May 26 09:22:06 volumio volumio[778]: error: updateQueue error: null May 26 09:22:19 volumio volumio[778]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 26 09:22:19 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 26 09:22:19 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 09:22:19 volumio volumio[778]: info: Output device has changed, restarting MPD May 26 09:22:19 volumio volumio[778]: info: Output device has changed, restarting Shairport Sync May 26 09:22:19 volumio sudo[7317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 09:22:19 volumio sudo[7317]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:22:19 volumio sudo[7317]: pam_unix(sudo:session): session closed for user root May 26 09:22:19 volumio sudo[7319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 09:22:19 volumio sudo[7319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 09:22:19 volumio systemd[1]: Stopping Music Player Daemon... May 26 09:22:19 volumio volumio[778]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 09:22:19 volumio systemd[1]: Starting Music Player Daemon... May 26 09:22:20 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 09:22:20 volumio volumio[778]: info: Found match in Cards Database: setting mixer Headphone for card Headphones May 26 09:22:20 volumio volumio[778]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 26 09:22:20 volumio volumio[778]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: Headphone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 May 26 09:22:21 volumio volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 09:22:21 volumio volumio[778]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 09:22:21 volumio volumio[778]: { Error: connect ETIMEDOUT 69.63.186.30:443 May 26 09:22:21 volumio volumio[778]: at Object._errnoException (util.js:1022:11) May 26 09:22:21 volumio volumio[778]: at _exceptionWithHostPort (util.js:1044:20) May 26 09:22:21 volumio volumio[778]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 26 09:22:21 volumio volumio[778]: code: 'ETIMEDOUT', May 26 09:22:21 volumio volumio[778]: errno: 'ETIMEDOUT', May 26 09:22:21 volumio volumio[778]: syscall: 'connect', May 26 09:22:21 volumio volumio[778]: address: '69.63.186.30', May 26 09:22:21 volumio volumio[778]: port: 443 } May 26 09:22:21 volumio volumio[778]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 09:22:27 volumio systemd[1]: Started Music Player Daemon. May 26 09:22:27 volumio sudo[7319]: pam_unix(sudo:session): session closed for user root May 26 09:22:30 volumio sudo[7359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-26 09:21 May 26 09:22:30 volumio sudo[7359]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"