-- Logs begin at Thu 2025-06-26 09:16:03 EDT, end at Thu 2025-06-26 09:25:27 EDT. -- Jun 26 09:24:01 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:01 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:03 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:03 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:05 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:05 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:07 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:07 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:09 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:09 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:11 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:11 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:13 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:13 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:15 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:15 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:17 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 09:24:17 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 09:24:17 volumio volumio[1208]: info: Discovery: Getting this device information Jun 26 09:24:17 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:17 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:17 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 09:24:17 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:17 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:19 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:19 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:21 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:21 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:22 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 26 09:24:22 volumio volumio[1208]: info: CURURI: playlists Jun 26 09:24:22 volumio volumio[1208]: info: Listing playlists Jun 26 09:24:22 volumio volumio[1208]: info: Preload queue cleared Jun 26 09:24:23 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:23 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:24 volumio volumio[1208]: info: Preload queue cleared Jun 26 09:24:25 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:25 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:26 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 26 09:24:26 volumio volumio[1208]: info: CURURI: music-library Jun 26 09:24:26 volumio volumio[1208]: info: Preload queue cleared Jun 26 09:24:27 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:27 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:29 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:29 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:31 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:31 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:33 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:33 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:35 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:35 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 09:24:36 volumio volumio[1208]: info: Getting Alsa Cards List without I2S DAC Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 26 09:24:36 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 09:24:37 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:37 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:39 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:39 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:41 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:41 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:43 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:43 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:45 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:45 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:47 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 09:24:47 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 09:24:47 volumio volumio[1208]: info: Discovery: Getting this device information Jun 26 09:24:47 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:47 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:47 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 09:24:47 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:47 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:49 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:49 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:51 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:51 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:53 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:53 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:55 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:55 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:57 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:57 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:24:59 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:24:59 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:01 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:01 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:03 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:03 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:05 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:05 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:07 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:07 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:09 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:09 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:11 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:11 volumio volumio[1208]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jun 26 09:25:11 volumio sudo[4093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 09:25:11 volumio sudo[4093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 09:25:11 volumio sudo[4093]: pam_unix(sudo:session): session closed for user root Jun 26 09:25:11 volumio volumio[1208]: info: MPD Permissions set Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio sudo[4096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 26 09:25:11 volumio sudo[4096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 09:25:11 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 09:25:11 volumio systemd[1]: Stopping Music Player Daemon... Jun 26 09:25:11 volumio kernel: voicehat-codec voicehat-codec: Disabling audio amp... Jun 26 09:25:11 volumio systemd[1]: mpd.service: Succeeded. Jun 26 09:25:11 volumio systemd[1]: Stopped Music Player Daemon. Jun 26 09:25:11 volumio systemd[1]: Starting Music Player Daemon... Jun 26 09:25:11 volumio sudo[4102]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 09:25:11 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 09:25:11 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Jun 26 09:25:12 volumio mpd[4104]: Jun 26 09:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 26 09:25:12 volumio systemd[1]: Started Music Player Daemon. Jun 26 09:25:12 volumio sudo[4096]: pam_unix(sudo:session): session closed for user root Jun 26 09:25:12 volumio volumio[1208]: error: updateQueue error: null Jun 26 09:25:12 volumio volumio[1208]: error: Upnp client error: Error: This socket has been ended by the other party Jun 26 09:25:13 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:13 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:15 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:15 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:17 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 09:25:17 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 09:25:17 volumio volumio[1208]: info: Discovery: Getting this device information Jun 26 09:25:17 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:17 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:17 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 09:25:17 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:17 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::volumioNext Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::next Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::stop Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::stPlaybackTimer Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::updateTrackBlock Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrackBlock Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::serviceStop Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 3 Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::serviceStop Jun 26 09:25:18 volumio volumio[1208]: info: ControllerMpd::stop Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand stop took 2 milliseconds Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::play index undefined Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::startPlaybackTimer Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::clearAddPlayTracks USB/77/24-192乐器精选(3-5) 管弦交响/01. 施特劳斯圆舞曲ii.wav Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::updateTrackBlock Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrackBlock Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand stop took 0 milliseconds Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand clear Jun 26 09:25:18 volumio volumio[1208]: info: Jun 26 09:25:18 volumio volumio[1208]: ---------------------------- MPD announces system playlist update Jun 26 09:25:18 volumio volumio[1208]: info: Ignoring MPD Status Update Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand clear took 1 milliseconds Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand add "USB/77/24-192乐器精选(3-5) 管弦交响/01. 施特劳斯圆舞曲ii.wav" Jun 26 09:25:18 volumio volumio[1208]: error: updateQueue error: null Jun 26 09:25:18 volumio volumio[1208]: info: Jun 26 09:25:18 volumio volumio[1208]: ---------------------------- MPD announces system playlist update Jun 26 09:25:18 volumio volumio[1208]: info: Ignoring MPD Status Update Jun 26 09:25:18 volumio volumio[1208]: info: ------------------------------ 1ms Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand add "USB/77/24-192乐器精选(3-5) 管弦交响/01. 施特劳斯圆舞曲ii.wav" took 0 milliseconds Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand play Jun 26 09:25:18 volumio volumio[1208]: info: ------------------------------ 1ms Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand play took 1 milliseconds Jun 26 09:25:18 volumio volumio[1208]: info: Jun 26 09:25:18 volumio volumio[1208]: ---------------------------- MPD announces state update: player Jun 26 09:25:18 volumio volumio[1208]: info: ControllerMpd::getState Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand status Jun 26 09:25:18 volumio volumio[1208]: info: Jun 26 09:25:18 volumio volumio[1208]: ---------------------------- MPD announces state update: player Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand status took 6 milliseconds Jun 26 09:25:18 volumio volumio[1208]: info: ControllerMpd::getState Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand status Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::parseState Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand status took 1 milliseconds Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::parseState Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::parseTrackInfo Jun 26 09:25:18 volumio volumio[1208]: info: ControllerMpd::pushState Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::servicePushState Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:18 volumio volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ʩ????˹Բ????ii","artist":"Ⱥ??","album":"???ҽ???","uri":"USB/77/24-192乐器精选(3-5) 管弦交响/01. 施特劳斯圆舞曲ii.wav","trackType":"wav"} Jun 26 09:25:18 volumio volumio[1208]: verbose: CURRENT POSITION 4 Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::syncState stateService play Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::syncState currentStatus stop Jun 26 09:25:18 volumio volumio[1208]: info: ------------------------------ 8ms Jun 26 09:25:18 volumio volumio[1208]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 26 09:25:18 volumio volumio[1208]: verbose: ControllerMpd::parseTrackInfo Jun 26 09:25:18 volumio volumio[1208]: info: ControllerMpd::pushState Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::servicePushState Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:18 volumio volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":190,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ʩ????˹Բ????ii","artist":"Ⱥ??","album":"???ҽ???","uri":"USB/77/24-192乐器精选(3-5) 管弦交响/01. 施特劳斯圆舞曲ii.wav","trackType":"wav"} Jun 26 09:25:18 volumio volumio[1208]: verbose: CURRENT POSITION 4 Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::syncState stateService play Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::syncState currentStatus play Jun 26 09:25:18 volumio volumio[1208]: info: Received an update from plugin. extracting info from payload Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:18 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:18 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:18 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:18 volumio volumio[1208]: info: ------------------------------ 8ms Jun 26 09:25:18 volumio kernel: voicehat-codec voicehat-codec: Enabling audio amp... Jun 26 09:25:19 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:19 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:21 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:21 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioNext Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::next Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::stop Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::stPlaybackTimer Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::updateTrackBlock Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrackBlock Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::serviceStop Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 4 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::serviceStop Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::stop Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 09:25:22 volumio kernel: voicehat-codec voicehat-codec: Disabling audio amp... Jun 26 09:25:22 volumio volumio[1208]: info: Jun 26 09:25:22 volumio volumio[1208]: ---------------------------- MPD announces state update: player Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand stop took 4 milliseconds Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::getState Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand status Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::play index undefined Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::startPlaybackTimer Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::clearAddPlayTracks USB/77/24-192乐器精选(3-5) 管弦交响/09. 北京喜讯传边寨.wav Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::updateTrackBlock Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrackBlock Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand status took 1 milliseconds Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand stop took 0 milliseconds Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::parseState Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand clear Jun 26 09:25:22 volumio volumio[1208]: info: Jun 26 09:25:22 volumio volumio[1208]: ---------------------------- MPD announces system playlist update Jun 26 09:25:22 volumio volumio[1208]: info: Ignoring MPD Status Update Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand clear took 0 milliseconds Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::parseTrackInfo Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand add "USB/77/24-192乐器精选(3-5) 管弦交响/09. 北京喜讯传边寨.wav" Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::servicePushState Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ʩ????˹Բ????ii","artist":"Ⱥ??","album":"???ҽ???","uri":"USB/77/24-192乐器精选(3-5) 管弦交响/01. 施特劳斯圆舞曲ii.wav","trackType":"wav"} Jun 26 09:25:22 volumio volumio[1208]: verbose: CURRENT POSITION 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::syncState stateService stop Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::syncState currentStatus stop Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:22 volumio volumio[1208]: info: No code Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:22 volumio volumio[1208]: info: ------------------------------ 10ms Jun 26 09:25:22 volumio volumio[1208]: error: updateQueue error: null Jun 26 09:25:22 volumio volumio[1208]: info: Jun 26 09:25:22 volumio volumio[1208]: ---------------------------- MPD announces system playlist update Jun 26 09:25:22 volumio volumio[1208]: info: Ignoring MPD Status Update Jun 26 09:25:22 volumio volumio[1208]: info: ------------------------------ 9ms Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand add "USB/77/24-192乐器精选(3-5) 管弦交响/09. 北京喜讯传边寨.wav" took 9 milliseconds Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand play Jun 26 09:25:22 volumio volumio[1208]: info: ------------------------------ 1ms Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand play took 1 milliseconds Jun 26 09:25:22 volumio volumio[1208]: info: Jun 26 09:25:22 volumio volumio[1208]: ---------------------------- MPD announces state update: player Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::getState Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand status Jun 26 09:25:22 volumio volumio[1208]: info: Jun 26 09:25:22 volumio volumio[1208]: ---------------------------- MPD announces state update: player Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand status took 5 milliseconds Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::getState Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand status Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::parseState Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand status took 0 milliseconds Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::parseState Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 26 09:25:22 volumio volumio[1208]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::parseTrackInfo Jun 26 09:25:22 volumio volumio[1208]: verbose: ControllerMpd::parseTrackInfo Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::servicePushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????ϲѶ????կ","artist":"Ⱥ??","album":"???ҽ???","uri":"USB/77/24-192乐器精选(3-5) 管弦交响/09. 北京喜讯传边寨.wav","trackType":"wav"} Jun 26 09:25:22 volumio volumio[1208]: verbose: CURRENT POSITION 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::syncState stateService play Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::syncState currentStatus stop Jun 26 09:25:22 volumio volumio[1208]: info: ControllerMpd::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::servicePushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????ϲѶ????կ","artist":"Ⱥ??","album":"???ҽ???","uri":"USB/77/24-192乐器精选(3-5) 管弦交响/09. 北京喜讯传边寨.wav","trackType":"wav"} Jun 26 09:25:22 volumio volumio[1208]: verbose: CURRENT POSITION 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::syncState stateService play Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::syncState currentStatus play Jun 26 09:25:22 volumio volumio[1208]: info: Received an update from plugin. extracting info from payload Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:22 volumio volumio[1208]: info: CoreStateMachine::pushState Jun 26 09:25:22 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:22 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Jun 26 09:25:22 volumio volumio[1208]: info: ------------------------------ 11ms Jun 26 09:25:22 volumio volumio[1208]: info: ------------------------------ 8ms Jun 26 09:25:22 volumio kernel: voicehat-codec voicehat-codec: Enabling audio amp... Jun 26 09:25:23 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:23 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:25 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 26 09:25:25 volumio volumio[1208]: info: CorePlayQueue::getTrack 5 Jun 26 09:25:27 volumio volumio[1208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 09:25:27 volumio volumio[1208]: Error: connect ETIMEDOUT 31.13.67.33:80 Jun 26 09:25:27 volumio volumio[1208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 26 09:25:27 volumio volumio[1208]: errno: -110, Jun 26 09:25:27 volumio volumio[1208]: code: 'ETIMEDOUT', Jun 26 09:25:27 volumio volumio[1208]: syscall: 'connect', Jun 26 09:25:27 volumio volumio[1208]: address: '31.13.67.33', Jun 26 09:25:27 volumio volumio[1208]: port: 80 Jun 26 09:25:27 volumio volumio[1208]: } Jun 26 09:25:27 volumio volumio[1208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 09:25:27 volumio sudo[4176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 09:24 Jun 26 09:25:27 volumio sudo[4176]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"