-- Logs begin at Thu 2019-02-14 10:12:02 UTC, end at Tue 2025-03-04 12:51:41 UTC. -- Mar 04 12:50:07 volumio kernel: usb 1-1.4: new full-speed USB device number 10 using xhci_hcd Mar 04 12:50:07 volumio kernel: usb 1-1.4: New USB device found, idVendor=1c07, idProduct=0003, bcdDevice= 5.00 Mar 04 12:50:07 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 04 12:50:07 volumio kernel: usb 1-1.4: Product: CEntrance DACport Mar 04 12:50:07 volumio kernel: usb 1-1.4: Manufacturer: CEntrance Inc. Mar 04 12:50:07 volumio kernel: usb 1-1.4: 1:1: cannot get freq at ep 0x1 Mar 04 12:50:07 volumio volumio[799]: info: Mar 04 12:50:07 volumio volumio[799]: ---------------------------- USB Audio Device Attached Mar 04 12:50:07 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 04 12:50:07 volumio volumio[799]: info: CoreCommandRouter::Close All Modals sent Mar 04 12:50:07 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 12:50:07 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 12:50:07 volumio volumio[799]: info: Setting default mixerSoftMaster for Softvolume device Mar 04 12:50:07 volumio volumio[799]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 04 12:50:07 volumio volumio[799]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 04 12:50:07 volumio volumio[799]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 12:50:07 volumio volumio[799]: info: Enable softmixer device for audio device undefined Mar 04 12:50:07 volumio volumio[799]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 04 12:50:07 volumio volumio[799]: info: Software Volume ALSA configuration written Mar 04 12:50:07 volumio volumio[799]: info: Preparing to generate the ALSA configuration file Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 12:50:08 volumio volumio[799]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 04 12:50:08 volumio volumio[799]: info: Reading ALSA contributions from plugins. Mar 04 12:50:08 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:08 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:08 volumio volumio[799]: info: Asound.conf file written Mar 04 12:50:08 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 04 12:50:08 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:08 volumio volumio[799]: info: Output device has changed, restarting MPD Mar 04 12:50:08 volumio sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 12:50:08 volumio volumio[799]: info: Output device has changed, restarting Shairport Sync Mar 04 12:50:08 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:08 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:08 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 12:50:08 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio systemd[1]: Stopping Music Player Daemon... Mar 04 12:50:08 volumio volumio[799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 12:50:08 volumio volumio[799]: info: Output device has changed, restarting MPD Mar 04 12:50:08 volumio systemd[1]: mpd.service: Succeeded. Mar 04 12:50:08 volumio systemd[1]: Stopped Music Player Daemon. Mar 04 12:50:08 volumio systemd[1]: Starting Music Player Daemon... Mar 04 12:50:08 volumio sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 12:50:08 volumio volumio[799]: info: Output device has changed, restarting Shairport Sync Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:08 volumio sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio sudo[2244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 12:50:08 volumio sudo[2244]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio sudo[2239]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 04 12:50:08 volumio volumio[799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 12:50:08 volumio volumio[799]: info: MPD Permissions set Mar 04 12:50:08 volumio volumio[799]: info: MPD Permissions set Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:08 volumio volumio[799]: info: Starting Shairport Sync Mar 04 12:50:08 volumio systemd[1]: mpd.service: Succeeded. Mar 04 12:50:08 volumio systemd[1]: Stopped Music Player Daemon. Mar 04 12:50:08 volumio volumio[799]: info: Starting Shairport Sync Mar 04 12:50:08 volumio systemd[1]: Starting Music Player Daemon... Mar 04 12:50:08 volumio sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 12:50:08 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 04 12:50:08 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 04 12:50:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 04 12:50:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 04 12:50:08 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 12:50:08 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:08 volumio sudo[2258]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:08 volumio volumio[799]: info: Shairport-Sync Started Mar 04 12:50:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 04 12:50:08 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 04 12:50:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 04 12:50:08 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 04 12:50:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 04 12:50:08 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:08 volumio volumio[799]: info: Shairport-Sync Started Mar 04 12:50:08 volumio mpd[2270]: Mar 04 12:50 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 12:50:08 volumio mpd[2270]: Mar 04 12:50 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 04 12:50:08 volumio mpd[2270]: Mar 04 12:50 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 04 12:50:09 volumio systemd[1]: Started Music Player Daemon. Mar 04 12:50:09 volumio sudo[2244]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:09 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:09 volumio volumio[799]: error: MPD error: The expression evaluated to a falsy value: Mar 04 12:50:09 volumio volumio[799]: assert.ok(self.idling) Mar 04 12:50:09 volumio volumio[799]: error: updateQueue error: null Mar 04 12:50:10 volumio volumio[799]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 04 12:50:10 volumio volumio[799]: info: CoreStateMachine::getcurrentVolume Mar 04 12:50:10 volumio volumio[799]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 12:50:10 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:10 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:10 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:10 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:10 volumio volumio[799]: info: CoreStateMachine::updateTrackBlock Mar 04 12:50:10 volumio volumio[799]: info: CorePlayQueue::getTrackBlock Mar 04 12:50:10 volumio volumio[799]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 12:50:10 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:10 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:10 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:14 volumio volumio[799]: error: updateQueue error: null Mar 04 12:50:20 volumio volumio[799]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 12:50:20 volumio volumio[799]: info: Setting default mixerSoftMaster for Softvolume device Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 04 12:50:20 volumio volumio[799]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 12:50:20 volumio volumio[799]: info: Enable softmixer device for audio device undefined Mar 04 12:50:20 volumio volumio[799]: info: Software Volume ALSA configuration written Mar 04 12:50:20 volumio volumio[799]: info: Preparing to generate the ALSA configuration file Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 12:50:20 volumio volumio[799]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 04 12:50:20 volumio volumio[799]: info: Reading ALSA contributions from plugins. Mar 04 12:50:20 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:20 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:20 volumio volumio[799]: info: Asound.conf file unchanged, so no further update is needed Mar 04 12:50:20 volumio volumio[799]: info: Output device has changed, restarting MPD Mar 04 12:50:20 volumio sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 12:50:20 volumio volumio[799]: info: Output device has changed, restarting Shairport Sync Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:20 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:20 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:20 volumio sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 12:50:20 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:20 volumio systemd[1]: Stopping Music Player Daemon... Mar 04 12:50:20 volumio volumio[799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 12:50:20 volumio volumio[799]: info: MPD Permissions set Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio systemd[1]: mpd.service: Succeeded. Mar 04 12:50:20 volumio systemd[1]: Stopped Music Player Daemon. Mar 04 12:50:20 volumio systemd[1]: Starting Music Player Daemon... Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 12:50:20 volumio volumio[799]: info: Starting Shairport Sync Mar 04 12:50:20 volumio sudo[2320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 12:50:20 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 12:50:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 04 12:50:20 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 04 12:50:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 04 12:50:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 04 12:50:20 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:20 volumio volumio[799]: info: Shairport-Sync Started Mar 04 12:50:21 volumio mpd[2326]: Mar 04 12:50 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 12:50:21 volumio mpd[2326]: Mar 04 12:50 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 04 12:50:21 volumio mpd[2326]: Mar 04 12:50 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 04 12:50:21 volumio systemd[1]: Started Music Player Daemon. Mar 04 12:50:21 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Mar 04 12:50:21 volumio volumio[799]: error: updateQueue error: null Mar 04 12:50:22 volumio volumio[799]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 04 12:50:22 volumio volumio[799]: info: CoreStateMachine::getcurrentVolume Mar 04 12:50:22 volumio volumio[799]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 12:50:22 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:22 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:22 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:22 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:22 volumio volumio[799]: info: CoreStateMachine::updateTrackBlock Mar 04 12:50:22 volumio volumio[799]: info: CorePlayQueue::getTrackBlock Mar 04 12:50:22 volumio volumio[799]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 12:50:22 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:22 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:22 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:26 volumio volumio[799]: info: CoreCommandRouter::volumioPlay Mar 04 12:50:26 volumio volumio[799]: verbose: UNSET VOLATILE Mar 04 12:50:26 volumio volumio[799]: info: CoreStateMachine::play index undefined Mar 04 12:50:26 volumio volumio[799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 12:50:26 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:26 volumio volumio[799]: info: CoreStateMachine::startPlaybackTimer Mar 04 12:50:26 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::clearAddPlayTracks USB/F7F8-3597/发烧人声/18.Rise Again.wav Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand stop took 0 milliseconds Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 12:50:26 volumio volumio[799]: info: Mar 04 12:50:26 volumio volumio[799]: ---------------------------- MPD announces system playlist update Mar 04 12:50:26 volumio volumio[799]: info: Ignoring MPD Status Update Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand clear took 1 milliseconds Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand add "USB/F7F8-3597/发烧人声/18.Rise Again.wav" Mar 04 12:50:26 volumio volumio[799]: error: updateQueue error: null Mar 04 12:50:26 volumio volumio[799]: info: Mar 04 12:50:26 volumio volumio[799]: ---------------------------- MPD announces system playlist update Mar 04 12:50:26 volumio volumio[799]: info: Ignoring MPD Status Update Mar 04 12:50:26 volumio volumio[799]: info: ------------------------------ 2ms Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand add "USB/F7F8-3597/发烧人声/18.Rise Again.wav" took 1 milliseconds Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand play Mar 04 12:50:26 volumio volumio[799]: info: ------------------------------ 2ms Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand play took 1 milliseconds Mar 04 12:50:26 volumio volumio[799]: info: Mar 04 12:50:26 volumio volumio[799]: ---------------------------- MPD announces state update: player Mar 04 12:50:26 volumio volumio[799]: info: ControllerMpd::getState Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Mar 04 12:50:26 volumio kernel: usb 1-1.4: 1:1: cannot get freq at ep 0x1 Mar 04 12:50:26 volumio volumio[799]: info: Mar 04 12:50:26 volumio volumio[799]: ---------------------------- MPD announces state update: player Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand status took 27 milliseconds Mar 04 12:50:26 volumio volumio[799]: info: ControllerMpd::getState Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::parseState Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand status took 1 milliseconds Mar 04 12:50:26 volumio volumio[799]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::parseState Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 12:50:26 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Mar 04 12:50:26 volumio volumio[799]: info: ControllerMpd::pushState Mar 04 12:50:26 volumio volumio[799]: info: CoreCommandRouter::servicePushState Mar 04 12:50:26 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:26 volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18.Rise Again.wav","artist":null,"album":null,"uri":"USB/F7F8-3597/发烧人声/18.Rise Again.wav","trackType":"wav"} Mar 04 12:50:26 volumio volumio[799]: verbose: CURRENT POSITION 16 Mar 04 12:50:26 volumio volumio[799]: info: CoreStateMachine::syncState stateService play Mar 04 12:50:26 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus stop Mar 04 12:50:26 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:26 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:26 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:26 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:27 volumio volumio[799]: info: ------------------------------ 37ms Mar 04 12:50:27 volumio volumio[799]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 12:50:27 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Mar 04 12:50:27 volumio volumio[799]: info: ControllerMpd::pushState Mar 04 12:50:27 volumio volumio[799]: info: CoreCommandRouter::servicePushState Mar 04 12:50:27 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:27 volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18.Rise Again.wav","artist":null,"album":null,"uri":"USB/F7F8-3597/发烧人声/18.Rise Again.wav","trackType":"wav"} Mar 04 12:50:27 volumio volumio[799]: verbose: CURRENT POSITION 16 Mar 04 12:50:27 volumio volumio[799]: info: CoreStateMachine::syncState stateService play Mar 04 12:50:27 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play Mar 04 12:50:27 volumio volumio[799]: info: Received an update from plugin. extracting info from payload Mar 04 12:50:27 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:27 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:27 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:27 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:27 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:27 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:27 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:27 volumio volumio[799]: info: ------------------------------ 21ms Mar 04 12:50:35 volumio volumio[799]: info: CoreCommandRouter::volumioSeek Mar 04 12:50:35 volumio volumio[799]: info: CoreStateMachine::seek Mar 04 12:50:35 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:35 volumio volumio[799]: info: TRACKBLOCK {"uri":"mnt/USB/F7F8-3597/发烧人声/18.Rise Again.wav","service":"mpd","name":"18.Rise Again.wav","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=804&web=//extralarge&path=%2FUSB%2FF7F8-3597%2F%E5%8F%91%E7%83%A7%E4%BA%BA%E5%A3%B0&metadata=false","duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"wav","channels":2} Mar 04 12:50:35 volumio volumio[799]: info: CoreStateMachine::startPlaybackTimer Mar 04 12:50:35 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:35 volumio volumio[799]: info: ControllerMpd::seek Mar 04 12:50:35 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:35 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:35 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:35 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:35 volumio volumio[799]: error: null Mar 04 12:50:35 volumio volumio[799]: info: Mar 04 12:50:35 volumio volumio[799]: ---------------------------- MPD announces state update: player Mar 04 12:50:35 volumio volumio[799]: info: ControllerMpd::getState Mar 04 12:50:35 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Mar 04 12:50:35 volumio volumio[799]: info: sendMpdCommand status took 1 milliseconds Mar 04 12:50:35 volumio volumio[799]: verbose: ControllerMpd::parseState Mar 04 12:50:35 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 12:50:35 volumio volumio[799]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 12:50:35 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Mar 04 12:50:35 volumio volumio[799]: info: ControllerMpd::pushState Mar 04 12:50:35 volumio volumio[799]: info: CoreCommandRouter::servicePushState Mar 04 12:50:35 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:36 volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18.Rise Again.wav","artist":null,"album":null,"uri":"USB/F7F8-3597/发烧人声/18.Rise Again.wav","trackType":"wav"} Mar 04 12:50:36 volumio volumio[799]: verbose: CURRENT POSITION 16 Mar 04 12:50:36 volumio volumio[799]: info: CoreStateMachine::syncState stateService play Mar 04 12:50:36 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play Mar 04 12:50:36 volumio volumio[799]: info: Received an update from plugin. extracting info from payload Mar 04 12:50:36 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:36 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:36 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:36 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:36 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:36 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:36 volumio volumio[799]: info: ------------------------------ 12ms Mar 04 12:50:39 volumio volumio[799]: info: CoreCommandRouter::volumioPause Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::pause Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::stPlaybackTimer Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::servicePause Mar 04 12:50:39 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:39 volumio volumio[799]: info: CoreCommandRouter::servicePause Mar 04 12:50:39 volumio volumio[799]: info: ControllerMpd::pause Mar 04 12:50:39 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand pause Mar 04 12:50:39 volumio volumio[799]: info: Mar 04 12:50:39 volumio volumio[799]: ---------------------------- MPD announces state update: player Mar 04 12:50:39 volumio volumio[799]: info: sendMpdCommand pause took 25 milliseconds Mar 04 12:50:39 volumio volumio[799]: info: ControllerMpd::getState Mar 04 12:50:39 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Mar 04 12:50:39 volumio volumio[799]: info: sendMpdCommand status took 1 milliseconds Mar 04 12:50:39 volumio volumio[799]: verbose: ControllerMpd::parseState Mar 04 12:50:39 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 12:50:39 volumio volumio[799]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 12:50:39 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Mar 04 12:50:39 volumio volumio[799]: info: ControllerMpd::pushState Mar 04 12:50:39 volumio volumio[799]: info: CoreCommandRouter::servicePushState Mar 04 12:50:39 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:39 volumio volumio[799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":62457,"duration":228,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18.Rise Again.wav","artist":null,"album":null,"uri":"USB/F7F8-3597/发烧人声/18.Rise Again.wav","trackType":"wav"} Mar 04 12:50:39 volumio volumio[799]: verbose: CURRENT POSITION 16 Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::syncState stateService pause Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus pause Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::pushState Mar 04 12:50:39 volumio volumio[799]: info: CorePlayQueue::getTrack 16 Mar 04 12:50:39 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 12:50:39 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Mar 04 12:50:39 volumio volumio[799]: info: CoreStateMachine::stPlaybackTimer Mar 04 12:50:39 volumio volumio[799]: info: ------------------------------ 11ms Mar 04 12:51:41 volumio volumio[799]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 12:51:41 volumio volumio[799]: Error: connect ETIMEDOUT 74.86.17.48:443 Mar 04 12:51:41 volumio volumio[799]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 04 12:51:41 volumio volumio[799]: errno: -110, Mar 04 12:51:41 volumio volumio[799]: code: 'ETIMEDOUT', Mar 04 12:51:41 volumio volumio[799]: syscall: 'connect', Mar 04 12:51:41 volumio volumio[799]: address: '74.86.17.48', Mar 04 12:51:41 volumio volumio[799]: port: 443 Mar 04 12:51:41 volumio volumio[799]: } Mar 04 12:51:41 volumio volumio[799]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 12:51:41 volumio sudo[2358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-04 12:50 Mar 04 12:51:41 volumio sudo[2358]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"