-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2026-01-10 12:57:51 CST. -- Jan 10 12:56:26 volumio kernel: usb 1-1.4: USB disconnect, device number 5 Jan 10 12:56:26 volumio volumio[827]: info: Jan 10 12:56:26 volumio volumio[827]: ---------------------------- MPD announces state update: player Jan 10 12:56:26 volumio volumio[827]: info: ControllerMpd::getState Jan 10 12:56:26 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Jan 10 12:56:26 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 10 12:56:26 volumio volumio[827]: info: sendMpdCommand status took 12 milliseconds Jan 10 12:56:26 volumio volumio[827]: verbose: ControllerMpd::parseState Jan 10 12:56:26 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 12:56:26 volumio volumio[827]: info: sendMpdCommand clearerror took 4 milliseconds Jan 10 12:56:26 volumio volumio[827]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 12:56:26 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Jan 10 12:56:26 volumio volumio[827]: info: ControllerMpd::pushState Jan 10 12:56:26 volumio volumio[827]: info: CoreCommandRouter::servicePushState Jan 10 12:56:26 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:26 volumio volumio[827]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":34108,"duration":231,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Rimsky-Korsakov","artist":"Tutti! - An Orchestral Sampler","album":"Tutti! - An Orchestral Sampler","uri":"USB/USB_DISK/无敌天碟TUTTI! (RR-906SACD)/01 - Rimsky-Korsakov.dsf","trackType":"dsf"} Jan 10 12:56:26 volumio volumio[827]: verbose: CURRENT POSITION 0 Jan 10 12:56:26 volumio volumio[827]: info: CoreStateMachine::syncState stateService pause Jan 10 12:56:26 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play Jan 10 12:56:26 volumio volumio[827]: info: CoreStateMachine::pushState Jan 10 12:56:26 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:26 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 12:56:26 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Jan 10 12:56:26 volumio volumio[827]: info: ------------------------------ 39ms Jan 10 12:56:26 volumio volumio[827]: info: Jan 10 12:56:26 volumio volumio[827]: ---------------------------- USB Audio Device Detached Jan 10 12:56:26 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jan 10 12:56:26 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 12:56:27 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 10 12:56:45 volumio kernel: usb 1-1.4: new high-speed USB device number 6 using xhci-hcd Jan 10 12:56:45 volumio kernel: usb 1-1.4: New USB device found, idVendor=22d9, idProduct=0436, bcdDevice= 6.f3 Jan 10 12:56:45 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jan 10 12:56:45 volumio kernel: usb 1-1.4: Product: DAP10 Internal digital interface Jan 10 12:56:45 volumio kernel: usb 1-1.4: Manufacturer: BRZHIFI AUDIO Jan 10 12:56:45 volumio kernel: usb 1-1.4: 1:2 : unsupported format bits 0x100000000 Jan 10 12:56:45 volumio systemd-udevd[14514]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jan 10 12:56:45 volumio volumio[827]: info: Jan 10 12:56:45 volumio volumio[827]: ---------------------------- USB Audio Device Attached Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::Close All Modals sent Jan 10 12:56:45 volumio volumio[827]: info: Preparing to save Alsa Options, stopping services first Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Jan 10 12:56:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::volumioPause Jan 10 12:56:45 volumio volumio[827]: info: CoreStateMachine::pause Jan 10 12:56:45 volumio volumio[827]: info: CoreStateMachine::stPlaybackTimer Jan 10 12:56:45 volumio volumio[827]: info: CoreStateMachine::servicePause Jan 10 12:56:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::servicePause Jan 10 12:56:45 volumio volumio[827]: info: ControllerMpd::pause Jan 10 12:56:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand pause Jan 10 12:56:45 volumio volumio[827]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DAP10 Internal digital interfac","alsacard":"interfac"},"i2s":false} Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 12:56:45 volumio volumio[827]: info: Setting mixer DAP10 Internal digital interface Playback for card DAP10 Internal digital interfac Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 12:56:45 volumio volumio[827]: info: Updating Volume Controller Parameters: Device: 5 Name: DAP10 Internal digital interfac Mixer: DAP10 Internal digital interface Playback Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 10 12:56:45 volumio volumio[827]: info: Disabling external Volume Control Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 12:56:45 volumio volumio[827]: info: Preparing to generate the ALSA configuration file Jan 10 12:56:45 volumio volumio[827]: info: Asound.conf file unchanged, so no further update is needed Jan 10 12:56:45 volumio volumio[827]: info: Output device has changed, restarting MPD Jan 10 12:56:45 volumio volumio[827]: info: Output device has changed, restarting Shairport Sync Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:56:45 volumio sudo[14534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 12:56:45 volumio sudo[14532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 12:56:45 volumio sudo[14534]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:56:45 volumio sudo[14532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:56:45 volumio sudo[14532]: pam_unix(sudo:session): session closed for user root Jan 10 12:56:45 volumio systemd[1]: Stopping Music Player Daemon... Jan 10 12:56:45 volumio volumio[827]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:56:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 12:56:45 volumio kernel: usb 1-1.4: 1:0: usb_set_interface failed (-71) Jan 10 12:56:45 volumio systemd[1]: mpd.service: Succeeded. Jan 10 12:56:45 volumio systemd[1]: Stopped Music Player Daemon. Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 12:56:46 volumio volumio[827]: info: Jan 10 12:56:46 volumio volumio[827]: ---------------------------- MPD announces state update: player Jan 10 12:56:46 volumio volumio[827]: info: sendMpdCommand pause took 405 milliseconds Jan 10 12:56:46 volumio volumio[827]: info: ControllerMpd::getState Jan 10 12:56:46 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Jan 10 12:56:46 volumio volumio[827]: error: MPD error: Error: write EPIPE Jan 10 12:56:46 volumio volumio[827]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 10 12:56:46 volumio volumio[827]: info: MPD Permissions set Jan 10 12:56:46 volumio volumio[827]: Unable to parse: Jan 10 12:56:46 volumio volumio[827]: Simple mixer control 'DAP10 Internal digital interface Playback ',0 Jan 10 12:56:46 volumio volumio[827]: Capabilities: pswitch Jan 10 12:56:46 volumio volumio[827]: Playback channels: Front Left - Front Right Jan 10 12:56:46 volumio volumio[827]: Mono: Jan 10 12:56:46 volumio volumio[827]: Front Left: Playback [on] Jan 10 12:56:46 volumio volumio[827]: Front Right: Playback [on] Jan 10 12:56:46 volumio volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio systemd[1]: Starting Music Player Daemon... Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:56:46 volumio volumio[827]: Unable to parse: Jan 10 12:56:46 volumio volumio[827]: Simple mixer control 'DAP10 Internal digital interface Playback ',0 Jan 10 12:56:46 volumio volumio[827]: Capabilities: pswitch Jan 10 12:56:46 volumio volumio[827]: Playback channels: Front Left - Front Right Jan 10 12:56:46 volumio volumio[827]: Mono: Jan 10 12:56:46 volumio volumio[827]: Front Left: Playback [on] Jan 10 12:56:46 volumio volumio[827]: Front Right: Playback [on] Jan 10 12:56:46 volumio volumio[827]: info: VolumeController:: Volume=undefined Mute =false Jan 10 12:56:46 volumio volumio[827]: info: CoreStateMachine::pushState Jan 10 12:56:46 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Jan 10 12:56:46 volumio volumio[827]: info: Starting Shairport Sync Jan 10 12:56:46 volumio sudo[14551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 12:56:46 volumio sudo[14551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:56:46 volumio kernel: usb 1-1.4: USB disconnect, device number 6 Jan 10 12:56:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 10 12:56:46 volumio sudo[14544]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 12:56:46 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 10 12:56:46 volumio sudo[14544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:56:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 10 12:56:46 volumio sudo[14551]: pam_unix(sudo:session): session closed for user root Jan 10 12:56:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 10 12:56:46 volumio volumio[827]: info: Shairport-Sync Started Jan 10 12:56:46 volumio sudo[14544]: pam_unix(sudo:session): session closed for user root Jan 10 12:56:46 volumio volumio[827]: info: Jan 10 12:56:46 volumio volumio[827]: ---------------------------- USB Audio Device Detached Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 10 12:56:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 10 12:56:46 volumio mpd[14557]: Jan 10 12:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 12:56:46 volumio systemd[1]: Started Music Player Daemon. Jan 10 12:56:46 volumio sudo[14534]: pam_unix(sudo:session): session closed for user root Jan 10 12:56:46 volumio volumio[827]: error: updateQueue error: null Jan 10 12:56:49 volumio volumio[827]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 10 12:56:49 volumio volumio[827]: info: CoreStateMachine::getcurrentVolume Jan 10 12:56:49 volumio volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 12:56:49 volumio volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 10 12:56:49 volumio sudo[14580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 10 12:56:49 volumio sudo[14580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:56:49 volumio volumio[827]: info: VolumeController:: Volume=undefined Mute =false Jan 10 12:56:49 volumio volumio[827]: info: CoreStateMachine::pushState Jan 10 12:56:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:49 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Jan 10 12:56:49 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock Jan 10 12:56:49 volumio volumio[827]: info: CorePlayQueue::getTrackBlock Jan 10 12:56:49 volumio volumio[827]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 12:56:49 volumio systemd[1]: Stopping MPD Monitor Service... Jan 10 12:56:49 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 10 12:56:49 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 10 12:56:49 volumio systemd[1]: Stopped MPD Monitor Service. Jan 10 12:56:49 volumio volumio[827]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 10 12:56:49 volumio volumio[827]: info: VolumeController:: Volume=undefined Mute =false Jan 10 12:56:49 volumio volumio[827]: info: CoreStateMachine::pushState Jan 10 12:56:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Jan 10 12:56:49 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Jan 10 12:56:49 volumio systemd[1]: Started MPD Monitor Service. Jan 10 12:56:49 volumio sudo[14580]: pam_unix(sudo:session): session closed for user root Jan 10 12:56:49 volumio mpd_monitor.sh[14584]: MPD Monitor Service: Starting MPD Monitor Service Jan 10 12:56:49 volumio volumio[827]: info: Successfully started MPD Monitor Jan 10 12:57:51 volumio volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 12:57:51 volumio volumio[827]: Error: connect ETIMEDOUT 199.16.156.75:443 Jan 10 12:57:51 volumio volumio[827]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 10 12:57:51 volumio volumio[827]: errno: -110, Jan 10 12:57:51 volumio volumio[827]: code: 'ETIMEDOUT', Jan 10 12:57:51 volumio volumio[827]: syscall: 'connect', Jan 10 12:57:51 volumio volumio[827]: address: '199.16.156.75', Jan 10 12:57:51 volumio volumio[827]: port: 443 Jan 10 12:57:51 volumio volumio[827]: } Jan 10 12:57:51 volumio volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 12:57:51 volumio sudo[14732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 12:56 Jan 10 12:57:51 volumio sudo[14732]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"