Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 04 08:04:02 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 08:04:06 volumio volumio[66142]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Mar 04 08:04:06 volumio sudo[67738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 08:04:06 volumio sudo[67736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 08:04:06 volumio sudo[67738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 08:04:06 volumio sudo[67736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 08:04:06 volumio sudo[67736]: pam_unix(sudo:session): session closed for user root Mar 04 08:04:06 volumio volumio[66142]: info: MPD Permissions set Mar 04 08:04:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 04 08:04:06 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 08:04:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 08:04:06 volumio systemd[1]: mpd.service: Consumed 2min 19.686s CPU time. Mar 04 08:04:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 08:04:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 08:04:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 08:04:06 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 08:04:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 08:04:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 08:04:06 volumio sudo[67750]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 08:04:06 volumio sudo[67750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 08:04:06 volumio sudo[67750]: pam_unix(sudo:session): session closed for user root Mar 04 08:04:06 volumio mpd[67752]: 2026-03-04T08:04:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 08:04:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 04 08:04:06 volumio sudo[67738]: pam_unix(sudo:session): session closed for user root Mar 04 08:04:06 volumio volumio[66142]: error: updateQueue error: null Mar 04 08:04:11 volumio volumio[66142]: info: CoreCommandRouter::volumioGetState Mar 04 08:04:11 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:12 volumio volumio[66142]: info: CoreCommandRouter::volumioPause Mar 04 08:04:12 volumio volumio[66142]: info: CoreStateMachine::pause Mar 04 08:04:12 volumio volumio[66142]: info: CoreStateMachine::stPlaybackTimer Mar 04 08:04:12 volumio volumio[66142]: info: CoreStateMachine::servicePause Mar 04 08:04:12 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:12 volumio volumio[66142]: info: CoreCommandRouter::servicePause Mar 04 08:04:12 volumio volumio[66142]: info: ControllerMpd::pause Mar 04 08:04:12 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand pause Mar 04 08:04:12 volumio volumio[66142]: info: sendMpdCommand pause took 0 milliseconds Mar 04 08:04:14 volumio volumio[66142]: info: CoreCommandRouter::volumioPause Mar 04 08:04:14 volumio volumio[66142]: info: CoreStateMachine::pause Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::volumioNext Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::next Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::stop Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::updateTrackBlock Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrackBlock Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::stPlaybackTimer Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::serviceStop Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::serviceStop Mar 04 08:04:15 volumio volumio[66142]: info: ControllerMpd::stop Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 08:04:15 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:15.257Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 08:04:15 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:15.257Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac" title=惊鸿一面 Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand stop took 3 milliseconds Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::play index undefined Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::startPlaybackTimer Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::clearAddPlayTracks NAS/Fn-NAS/0015/幻听 - 许嵩.flac Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::updateTrackBlock Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrackBlock Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand stop took 1 milliseconds Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 08:04:15 volumio volumio[66142]: info: Mar 04 08:04:15 volumio volumio[66142]: ---------------------------- MPD announces system playlist update Mar 04 08:04:15 volumio volumio[66142]: info: Ignoring MPD Status Update Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand clear took 1 milliseconds Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand add "NAS/Fn-NAS/0015/幻听 - 许嵩.flac" Mar 04 08:04:15 volumio volumio[66142]: error: updateQueue error: null Mar 04 08:04:15 volumio volumio[66142]: info: ------------------------------ 1ms Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand add "NAS/Fn-NAS/0015/幻听 - 许嵩.flac" took 1 milliseconds Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand play Mar 04 08:04:15 volumio volumio[66142]: info: Mar 04 08:04:15 volumio volumio[66142]: ---------------------------- MPD announces system playlist update Mar 04 08:04:15 volumio volumio[66142]: info: Ignoring MPD Status Update Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand play took 2 milliseconds Mar 04 08:04:15 volumio volumio[66142]: info: ------------------------------ 1ms Mar 04 08:04:15 volumio volumio[66142]: info: Mar 04 08:04:15 volumio volumio[66142]: ---------------------------- MPD announces state update: player Mar 04 08:04:15 volumio volumio[66142]: info: ControllerMpd::getState Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:04:15 volumio volumio[66142]: info: Mar 04 08:04:15 volumio volumio[66142]: ---------------------------- MPD announces state update: player Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand status took 2 milliseconds Mar 04 08:04:15 volumio volumio[66142]: info: ControllerMpd::getState Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::parseState Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand status took 1 milliseconds Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::parseState Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:04:15 volumio volumio[66142]: info: ControllerMpd::pushState Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::servicePushState Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:15 volumio volumio[66142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"幻听","artist":"许嵩","album":"梦游计","uri":"NAS/Fn-NAS/0015/幻听 - 许嵩.flac","trackType":"flac"} Mar 04 08:04:15 volumio volumio[66142]: verbose: CURRENT POSITION 5 Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::syncState stateService play Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::syncState currentStatus stop Mar 04 08:04:15 volumio volumio[66142]: info: ------------------------------ 5ms Mar 04 08:04:15 volumio volumio[66142]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:04:15 volumio volumio[66142]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:04:15 volumio volumio[66142]: info: ControllerMpd::pushState Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::servicePushState Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:15 volumio volumio[66142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"幻听","artist":"许嵩","album":"梦游计","uri":"NAS/Fn-NAS/0015/幻听 - 许嵩.flac","trackType":"flac"} Mar 04 08:04:15 volumio volumio[66142]: verbose: CURRENT POSITION 5 Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::syncState stateService play Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:04:15 volumio volumio[66142]: info: Received an update from plugin. extracting info from payload Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:15 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:15 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:15 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:15 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:15.346Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 08:04:15 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:15.346Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:15 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:15.346Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 08:04:15 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:15.346Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:15 volumio volumio[66142]: info: ------------------------------ 9ms Mar 04 08:04:15 volumio volumio[66142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B5%A9/%E6%A2%A6%E6%B8%B8%E8%AE%A1/8c985bc7-bc3f-4e49-9ae4-990ff2d867f3.jpg' Mar 04 08:04:15 volumio volumio[66142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B5%A9/%E6%A2%A6%E6%B8%B8%E8%AE%A1/8c985bc7-bc3f-4e49-9ae4-990ff2d867f3.jpg' Mar 04 08:04:15 volumio volumio[66142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B5%A9/%E6%A2%A6%E6%B8%B8%E8%AE%A1/8c985bc7-bc3f-4e49-9ae4-990ff2d867f3.jpg' Mar 04 08:04:16 volumio volumio[66142]: info: CoreCommandRouter::volumioPrevious Mar 04 08:04:16 volumio volumio[66142]: info: CoreStateMachine::previous Mar 04 08:04:16 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:16 volumio volumio[66142]: info: ControllerMpd::seek Mar 04 08:04:16 volumio volumio[66142]: error: null Mar 04 08:04:16 volumio volumio[66142]: info: Mar 04 08:04:16 volumio volumio[66142]: ---------------------------- MPD announces state update: player Mar 04 08:04:16 volumio volumio[66142]: info: ControllerMpd::getState Mar 04 08:04:16 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:04:16 volumio volumio[66142]: info: sendMpdCommand status took 0 milliseconds Mar 04 08:04:16 volumio volumio[66142]: verbose: ControllerMpd::parseState Mar 04 08:04:16 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:04:16 volumio volumio[66142]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:04:16 volumio volumio[66142]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:04:16 volumio volumio[66142]: info: ControllerMpd::pushState Mar 04 08:04:16 volumio volumio[66142]: info: CoreCommandRouter::servicePushState Mar 04 08:04:16 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:16 volumio volumio[66142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4200 Kbps","isStreaming":false,"title":"幻听","artist":"许嵩","album":"梦游计","uri":"NAS/Fn-NAS/0015/幻听 - 许嵩.flac","trackType":"flac"} Mar 04 08:04:16 volumio volumio[66142]: verbose: CURRENT POSITION 5 Mar 04 08:04:16 volumio volumio[66142]: info: CoreStateMachine::syncState stateService play Mar 04 08:04:16 volumio volumio[66142]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:04:16 volumio volumio[66142]: info: Received an update from plugin. extracting info from payload Mar 04 08:04:16 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:16 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:16 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:16 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:16 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:16 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:16 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:16.795Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 08:04:16 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:16.795Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:16 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:16.795Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 08:04:16 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:16.795Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:16 volumio volumio[66142]: info: ------------------------------ 8ms Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::volumioPrevious Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::previous Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::stop Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::stPlaybackTimer Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::updateTrackBlock Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrackBlock Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::serviceStop Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::serviceStop Mar 04 08:04:17 volumio volumio[66142]: info: ControllerMpd::stop Mar 04 08:04:17 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.900Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.900Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:17 volumio volumio[66142]: info: Mar 04 08:04:17 volumio volumio[66142]: ---------------------------- MPD announces state update: player Mar 04 08:04:17 volumio volumio[66142]: info: sendMpdCommand stop took 4 milliseconds Mar 04 08:04:17 volumio volumio[66142]: info: ControllerMpd::getState Mar 04 08:04:17 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:04:17 volumio volumio[66142]: info: sendMpdCommand status took 0 milliseconds Mar 04 08:04:17 volumio volumio[66142]: verbose: ControllerMpd::parseState Mar 04 08:04:17 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:04:17 volumio volumio[66142]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:04:17 volumio volumio[66142]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:04:17 volumio volumio[66142]: info: ControllerMpd::pushState Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::servicePushState Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: 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":"幻听","artist":"许嵩","album":"梦游计","uri":"NAS/Fn-NAS/0015/幻听 - 许嵩.flac","trackType":"flac"} Mar 04 08:04:17 volumio volumio[66142]: verbose: CURRENT POSITION 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::syncState stateService stop Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::syncState currentStatus stop Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:17 volumio volumio[66142]: info: No code Mar 04 08:04:17 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:17 volumio volumio[66142]: info: CorePlayQueue::getTrack 5 Mar 04 08:04:17 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.910Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.910Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.910Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.910Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.910Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 08:04:17 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:17.911Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0015/幻听 - 许嵩.flac" title=幻听 Mar 04 08:04:17 volumio volumio[66142]: info: ------------------------------ 11ms Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::play index undefined Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 08:04:18 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::startPlaybackTimer Mar 04 08:04:18 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::clearAddPlayTracks NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand stop took 1 milliseconds Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 08:04:18 volumio volumio[66142]: info: Mar 04 08:04:18 volumio volumio[66142]: ---------------------------- MPD announces system playlist update Mar 04 08:04:18 volumio volumio[66142]: info: Ignoring MPD Status Update Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand clear took 0 milliseconds Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand add "NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac" Mar 04 08:04:18 volumio volumio[66142]: error: updateQueue error: null Mar 04 08:04:18 volumio volumio[66142]: info: ------------------------------ 0ms Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand add "NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac" took 0 milliseconds Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand play Mar 04 08:04:18 volumio volumio[66142]: info: Mar 04 08:04:18 volumio volumio[66142]: ---------------------------- MPD announces system playlist update Mar 04 08:04:18 volumio volumio[66142]: info: Ignoring MPD Status Update Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand play took 0 milliseconds Mar 04 08:04:18 volumio volumio[66142]: info: ------------------------------ 1ms Mar 04 08:04:18 volumio volumio[66142]: info: Mar 04 08:04:18 volumio volumio[66142]: ---------------------------- MPD announces state update: player Mar 04 08:04:18 volumio volumio[66142]: info: ControllerMpd::getState Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:04:18 volumio volumio[66142]: info: Mar 04 08:04:18 volumio volumio[66142]: ---------------------------- MPD announces state update: player Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand status took 1 milliseconds Mar 04 08:04:18 volumio volumio[66142]: info: ControllerMpd::getState Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::parseState Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand status took 0 milliseconds Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::parseState Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:04:18 volumio volumio[66142]: info: ControllerMpd::pushState Mar 04 08:04:18 volumio volumio[66142]: info: CoreCommandRouter::servicePushState Mar 04 08:04:18 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:18 volumio volumio[66142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"惊鸿一面","artist":"许嵩/黄龄","album":"不如吃茶去","uri":"NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac","trackType":"flac"} Mar 04 08:04:18 volumio volumio[66142]: verbose: CURRENT POSITION 4 Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::syncState stateService play Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::syncState currentStatus stop Mar 04 08:04:18 volumio volumio[66142]: info: ------------------------------ 2ms Mar 04 08:04:18 volumio volumio[66142]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:04:18 volumio volumio[66142]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:04:18 volumio volumio[66142]: info: ControllerMpd::pushState Mar 04 08:04:18 volumio volumio[66142]: info: CoreCommandRouter::servicePushState Mar 04 08:04:18 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:18 volumio volumio[66142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"惊鸿一面","artist":"许嵩/黄龄","album":"不如吃茶去","uri":"NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac","trackType":"flac"} Mar 04 08:04:18 volumio volumio[66142]: verbose: CURRENT POSITION 4 Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::syncState stateService play Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:04:18 volumio volumio[66142]: info: Received an update from plugin. extracting info from payload Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:18 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:18 volumio volumio[66142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:04:18 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:18 volumio volumio[66142]: info: CoreStateMachine::pushState Mar 04 08:04:18 volumio volumio[66142]: info: CorePlayQueue::getTrack 4 Mar 04 08:04:18 volumio volumio[66142]: info: CoreCommandRouter::volumioPushState Mar 04 08:04:18 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:18.427Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 08:04:18 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:18.427Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac" title=惊鸿一面 Mar 04 08:04:18 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:18.427Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 08:04:18 volumio volumio5-onboarding[1648]: time=2026-03-04T08:04:18.427Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.23:59334 @ 0xc00051c870" id="mnt/NAS/Fn-NAS/0009/惊鸿一面 - 许嵩;黄龄.flac" title=惊鸿一面 Mar 04 08:04:18 volumio volumio[66142]: info: ------------------------------ 12ms Mar 04 08:04:22 volumio volumio[66142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 08:04:22 volumio volumio[66142]: Error: connect ETIMEDOUT 199.96.62.41:443 Mar 04 08:04:22 volumio volumio[66142]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 04 08:04:22 volumio volumio[66142]: errno: -110, Mar 04 08:04:22 volumio volumio[66142]: code: 'ETIMEDOUT', Mar 04 08:04:22 volumio volumio[66142]: syscall: 'connect', Mar 04 08:04:22 volumio volumio[66142]: address: '199.96.62.41', Mar 04 08:04:22 volumio volumio[66142]: port: 443 Mar 04 08:04:22 volumio volumio[66142]: } Mar 04 08:04:22 volumio volumio[66142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 08:04:22 volumio sudo[67849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 08:03' Mar 04 08:04:22 volumio sudo[67849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"