Oct 14 12:20:01 j1800 volumio[5849]: info: Oct 14 12:20:01 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:01 j1800 volumio[5849]: info: Oct 14 12:20:01 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:01 j1800 volumio[5849]: info: Oct 14 12:20:01 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:01 j1800 volumio[5849]: info: Oct 14 12:20:01 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:01 j1800 volumio[5849]: info: Oct 14 12:20:01 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:01 j1800 volumio[5849]: info: Oct 14 12:20:01 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:01 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand status took 14 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:01 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand status took 15 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand status took 14 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:01 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand status took 43 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand status took 47 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand clearerror took 39 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand status took 39 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand clearerror took 38 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand clearerror took 37 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:01 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: No code Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:01 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: No code Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:01 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: No code Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.879+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.879+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.881+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.882+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.882+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.883+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.883+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.885+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.884+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.884+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.886+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.888+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.889+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5504 volume=38 Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.890+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.893+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.893+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.897+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:01.898+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:01 j1800 volumio[5849]: info: ------------------------------ 145ms Oct 14 12:20:01 j1800 volumio[5849]: info: ------------------------------ 143ms Oct 14 12:20:01 j1800 volumio[5849]: info: ------------------------------ 143ms Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand clearerror took 139 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand clearerror took 133 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 121 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 120 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 120 milliseconds Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:01 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:01 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: No code Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:01 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: No code Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:01 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:01 j1800 volumio[5849]: info: No code Oct 14 12:20:01 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:01 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:01 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.009+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.012+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.012+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.013+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.013+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.013+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.020+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.020+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.021+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.021+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.024+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.024+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.024+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.027+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.030+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.030+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5885 volume=38 Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.034+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:02.034+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:02 j1800 volumio[5849]: info: ------------------------------ 270ms Oct 14 12:20:02 j1800 volumio[5849]: info: ------------------------------ 270ms Oct 14 12:20:02 j1800 volumio[5849]: info: ------------------------------ 269ms Oct 14 12:20:04 j1800 volumio[5849]: info: BOOT COMPLETED Oct 14 12:20:05 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:05.307+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.81.104:60803 @ 0xc000348450" latency=1.627681ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 14 12:20:05 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:05.336+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s Oct 14 12:20:05 j1800 sudo[6213]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 12:20:05 j1800 sudo[6213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:20:05 j1800 sudo[6213]: pam_unix(sudo:session): session closed for user root Oct 14 12:20:05 j1800 sudo[6215]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 12:20:05 j1800 sudo[6215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:20:05 j1800 sudo[6215]: pam_unix(sudo:session): session closed for user root Oct 14 12:20:05 j1800 volumio[5849]: verbose: New Socket.io Connection to 192.168.81.248 from 192.168.81.104 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 14 12:20:06 j1800 sudo[6220]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 12:20:06 j1800 sudo[6220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:20:06 j1800 sudo[6222]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 12:20:06 j1800 sudo[6222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:20:06 j1800 sudo[6220]: pam_unix(sudo:session): session closed for user root Oct 14 12:20:06 j1800 sudo[6222]: pam_unix(sudo:session): session closed for user root Oct 14 12:20:06 j1800 volumio[5849]: verbose: New Socket.io Connection to 192.168.81.248 from 192.168.81.104 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::volumioGetState Oct 14 12:20:06 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 12:20:06 j1800 volumio[5849]: info: Received Get System Info Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 12:20:06 j1800 volumio[5849]: info: Discovery: Getting this device information Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::volumioGetState Oct 14 12:20:06 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::volumioGetState Oct 14 12:20:06 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:06 j1800 volumio[5849]: info: Listing playlists Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 12:20:06 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:06.129+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://functions.volumio.cloud duration=791.888387ms Oct 14 12:20:06 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:06.129+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://functions.volumio.cloud duration=790.856025ms Oct 14 12:20:06 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:06.276+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=940.251613ms Oct 14 12:20:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 14 12:20:06 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:06.707+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://database.volumio.cloud duration=1.368220405s Oct 14 12:20:07 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:07.236+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=http://pushupdates.volumio.org duration=1.898144241s Oct 14 12:20:07 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:07.321+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.985059389s Oct 14 12:20:07 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 14 12:20:07 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 12:20:07 j1800 volumio[5849]: info: Received Get System Info Oct 14 12:20:07 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 12:20:07 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 12:20:07 j1800 volumio[5849]: info: Discovery: Getting this device information Oct 14 12:20:07 j1800 volumio[5849]: info: CoreCommandRouter::volumioGetState Oct 14 12:20:07 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:07 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 12:20:08 j1800 volumio[5849]: info: CoreCommandRouter::volumioPlay Oct 14 12:20:08 j1800 volumio[5849]: info: CoreStateMachine::play index undefined Oct 14 12:20:08 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:20:08 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:08 j1800 volumio[5849]: info: CoreStateMachine::startPlaybackTimer Oct 14 12:20:08 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:08 j1800 volumio[5849]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac Oct 14 12:20:08 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 12:20:08 j1800 volumio[5849]: info: sendMpdCommand stop took 0 milliseconds Oct 14 12:20:08 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 12:20:08 j1800 volumio[5849]: info: Oct 14 12:20:08 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:08 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:08 j1800 volumio[5849]: info: sendMpdCommand clear took 1 milliseconds Oct 14 12:20:08 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:08 j1800 volumio[5849]: info: Oct 14 12:20:08 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:08 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:08 j1800 volumio[5849]: error: updateQueue error: null Oct 14 12:20:08 j1800 volumio[5849]: info: Oct 14 12:20:08 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:08 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:08 j1800 volumio[5849]: info: ------------------------------ 6ms Oct 14 12:20:08 j1800 volumio[5849]: info: sendMpdCommand add "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" took 5 milliseconds Oct 14 12:20:08 j1800 volumio[5849]: info: ------------------------------ 4ms Oct 14 12:20:08 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand play Oct 14 12:20:08 j1800 volumio[5849]: info: Oct 14 12:20:08 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:08 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:08 j1800 volumio[5849]: info: Oct 14 12:20:08 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:08 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:08 j1800 volumio[5849]: info: ------------------------------ 5ms Oct 14 12:20:08 j1800 volumio[5849]: info: sendMpdCommand play took 3 milliseconds Oct 14 12:20:08 j1800 volumio[5849]: info: ------------------------------ 3ms Oct 14 12:20:08 j1800 volumio[5849]: info: ------------------------------ 2ms Oct 14 12:20:08 j1800 volumio[5849]: info: Oct 14 12:20:08 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:08 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:08 j1800 volumio[5849]: info: ------------------------------ 1ms Oct 14 12:20:09 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:09.247+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=http://cddb.volumio.org duration=3.910705454s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 12:20:10 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:10.033+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=4.697611902s Oct 14 12:20:14 j1800 volumio[5849]: info: Oct 14 12:20:14 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:14 j1800 volumio[5849]: info: Oct 14 12:20:14 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:14 j1800 volumio[5849]: info: Oct 14 12:20:14 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:14 j1800 volumio[5849]: info: Oct 14 12:20:14 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:14 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand status took 8 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:14 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand status took 9 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:14 j1800 volumio[5849]: info: Oct 14 12:20:14 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:14 j1800 volumio[5849]: info: Oct 14 12:20:14 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:14 j1800 volumio[5849]: error: MPD returned error for command status: Failed to decode "NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac": unrecognized file Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand status took 23 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand clearerror took 18 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand status took 17 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand clearerror took 17 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:14 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: No code Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:14 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: No code Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.161+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.162+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.163+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.163+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.164+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.165+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.166+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.166+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.168+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.168+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.170+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.170+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio[5849]: info: ------------------------------ 94ms Oct 14 12:20:14 j1800 volumio[5849]: info: ------------------------------ 94ms Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand status took 100 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand status took 99 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand clearerror took 93 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 87 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:14 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: No code Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:14 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: No code Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.243+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.244+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.250+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.250+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.251+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.251+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.251+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.252+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.252+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.252+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.252+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5257 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.253+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio[5849]: info: ------------------------------ 181ms Oct 14 12:20:14 j1800 volumio[5849]: info: ------------------------------ 182ms Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 65 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 65 milliseconds Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:14 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:14 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: No code Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: 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":"再度重相逢 (3D环绕版)-孙露.flac","artist":null,"album":null,"uri":"NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac","trackType":"flac"} Oct 14 12:20:14 j1800 volumio[5849]: verbose: CURRENT POSITION 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio[5849]: info: No code Oct 14 12:20:14 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:14 j1800 volumio[5849]: info: CorePlayQueue::getTrack 0 Oct 14 12:20:14 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.326+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5455 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.326+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.328+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5455 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.328+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.330+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5455 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.330+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.332+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5455 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.332+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.333+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5455 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.334+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.336+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=5455 volume=38 Oct 14 12:20:14 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:14.336+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/再度重相逢 (3D环绕版)-孙露.flac" title="再度重相逢 (3D环绕版)-孙露.flac" Oct 14 12:20:14 j1800 volumio[5849]: info: ------------------------------ 250ms Oct 14 12:20:14 j1800 volumio[5849]: info: ------------------------------ 248ms Oct 14 12:20:15 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:15.336+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=9.998711982s error="Head \"https://myvolumio.firebaseio.com\": context deadline exceeded" Oct 14 12:20:15 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:15.336+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://google.com duration=10.000501384s error="Head \"https://google.com\": context deadline exceeded" Oct 14 12:20:15 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:15.336+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=http://plugins.volumio.org duration=9.997789363s error="Head \"http://plugins.volumio.workers.dev/\": context deadline exceeded" Oct 14 12:20:15 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:15.336+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://securetoken.googleapis.com duration=9.999116644s error="Head \"https://securetoken.googleapis.com\": context deadline exceeded" Oct 14 12:20:15 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:15.336+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.81.104:60803 @ 0xc000348450" latency=-1.956581ms timeout=10s endpoint=https://www.googleapis.com duration=9.999796438s error="Head \"https://www.googleapis.com\": context deadline exceeded" Oct 14 12:20:16 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioNext Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::next Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::stop Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::play index undefined Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::startPlaybackTimer Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/周治平 - 我把心遗落在1989.flac Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::updateTrackBlock Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrackBlock Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand stop took 2 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:18 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand clear took 1 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:18 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:18 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:18 j1800 volumio[5849]: error: updateQueue error: null Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:18 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 4ms Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand add "NAS/NAS/周治平 - 我把心遗落在1989.flac" took 3 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 3ms Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 2ms Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand play Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:18 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:18 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 4ms Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand play took 3 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 2ms Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 1ms Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand status took 7 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand status took 8 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand status took 8 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:18 j1800 volumio[5849]: info: Oct 14 12:20:18 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand status took 12 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand status took 7 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:18 j1800 volumio[5849]: verbose: CURRENT POSITION 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:18 j1800 volumio[5849]: verbose: CURRENT POSITION 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:18 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:18 j1800 volumio[5849]: verbose: CURRENT POSITION 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:18 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 66ms Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.893+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.893+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.893+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.894+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.896+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.896+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.898+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.899+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 103ms Oct 14 12:20:18 j1800 volumio[5849]: info: ------------------------------ 105ms Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand status took 89 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 79 milliseconds Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:18 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"543 Kbps","isStreaming":false,"title":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:18 j1800 volumio[5849]: verbose: CURRENT POSITION 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:18 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"543 Kbps","isStreaming":false,"title":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:18 j1800 volumio[5849]: verbose: CURRENT POSITION 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:18 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:18 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:18 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.980+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=844 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.981+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.983+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=844 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.986+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.987+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=844 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.987+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.987+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=844 volume=38 Oct 14 12:20:18 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:18.987+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:19 j1800 volumio[5849]: info: ------------------------------ 180ms Oct 14 12:20:19 j1800 volumio[5849]: info: ------------------------------ 171ms Oct 14 12:20:19 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 105 milliseconds Oct 14 12:20:19 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:19 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:19 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:19 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:19 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"543 Kbps","isStreaming":false,"title":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:19 j1800 volumio[5849]: verbose: CURRENT POSITION 1 Oct 14 12:20:19 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:19 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:19 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:19 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:19 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:19 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:19 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:19 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:19 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:19 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:19.065+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=844 volume=38 Oct 14 12:20:19 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:19.066+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:19 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:19.066+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=844 volume=38 Oct 14 12:20:19 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:19.066+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:19 j1800 volumio[5849]: info: ------------------------------ 243ms Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::volumioNext Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::next Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::stop Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::stPlaybackTimer Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::updateTrackBlock Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrackBlock Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::serviceStop Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::serviceStop Oct 14 12:20:25 j1800 volumio[5849]: info: ControllerMpd::stop Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.914+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=0 volume=38 Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.916+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周治平 - 我把心遗落在1989.flac" title="周治平 - 我把心遗落在1989.flac" Oct 14 12:20:25 j1800 volumio[5849]: info: Oct 14 12:20:25 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand stop took 18 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::play index undefined Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::startPlaybackTimer Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/周虹 - 冷静.flac Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::updateTrackBlock Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrackBlock Oct 14 12:20:25 j1800 volumio[5849]: info: Oct 14 12:20:25 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:25 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:25 j1800 volumio[5849]: info: Oct 14 12:20:25 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:25 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand status took 6 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand stop took 4 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand status took 2 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand status took 1 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:25 j1800 volumio[5849]: info: Oct 14 12:20:25 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:25 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand clear took 5 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/周虹 - 冷静.flac" Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:25 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:25 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:25 j1800 volumio[5849]: 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":"周治平 - 我把心遗落在1989.flac","artist":null,"album":null,"uri":"NAS/NAS/周治平 - 我把心遗落在1989.flac","trackType":"flac"} Oct 14 12:20:25 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:25 j1800 volumio[5849]: info: No code Oct 14 12:20:25 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:25 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:25 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:25 j1800 volumio[5849]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 12:20:25 j1800 volumio[5849]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.970+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=4 volume=38 Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.971+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.973+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=4 volume=38 Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.974+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.976+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=4 volume=38 Oct 14 12:20:25 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:25.977+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:25 j1800 volumio[5849]: info: ------------------------------ 63ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 64ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 63ms Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:26 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:26 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:26 j1800 volumio[5849]: error: updateQueue error: null Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:26 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 60ms Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand add "NAS/NAS/周虹 - 冷静.flac" took 58 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand play Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 24ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 23ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 23ms Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand play took 23 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:26 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:20:26 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 3ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 3ms Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:26 j1800 volumio[5849]: info: Oct 14 12:20:26 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand status took 12 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand status took 12 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand status took 12 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand status took 6 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand status took 9 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand status took 9 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:26 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:20:26 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:20:26 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:26 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:20:26 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:26 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"173 Kbps","isStreaming":false,"title":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:20:26 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:26 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:20:26 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:26 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":433,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"173 Kbps","isStreaming":false,"title":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:20:26 j1800 volumio[5849]: verbose: CURRENT POSITION 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:20:26 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:26 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:26 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 89ms Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.464+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.464+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.468+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.468+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.473+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.473+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.475+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=0 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.478+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.479+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=114 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.479+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.483+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=114 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.483+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=228 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.483+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.483+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.484+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=228 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.485+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.487+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=433 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.487+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.491+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=433 volume=38 Oct 14 12:20:26 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:26.494+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 192ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 192ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 188ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 185ms Oct 14 12:20:26 j1800 volumio[5849]: info: ------------------------------ 184ms Oct 14 12:20:48 j1800 volumio[5849]: info: CoreCommandRouter::volumioGetQueue Oct 14 12:20:48 j1800 volumio[5849]: info: CoreStateMachine::getQueue Oct 14 12:20:48 j1800 volumio[5849]: info: CorePlayQueue::getQueue Oct 14 12:20:55 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BE%9A%E7%8E%A5/80%E6%81%8B%E6%AD%8C/a425c520-1462-4a0e-8961-4f3060731f3c.jpg' Oct 14 12:20:55 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E9%B8%BF/87%E7%8B%82%E7%83%AD/f66fde91-79ad-451e-acc7-ac6d929f59f6.jpg' Oct 14 12:20:55 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/2002%E5%B9%B4%E7%9A%84%E7%AC%AC%E4%B8%80%E5%9C%BA%E9%9B%AA/d03a8c67-a51c-4be7-bb59-30d27a35fcea.jpg' Oct 14 12:20:56 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/deca%20joins/Go%20Slow/d991e52d-3010-4143-9501-ed7658dface2.jpg' Oct 14 12:20:56 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BE%9A%E7%8E%A5/70%E6%81%8B%E6%AD%8C/0edcb438-224f-47f5-8a9e-d34952810963.jpg' Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:20:56 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:56 j1800 volumio[5849]: info: CoreStateMachine::setRandom true Oct 14 12:20:56 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:56 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:56 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:56.256+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=30171 volume=38 Oct 14 12:20:56 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:56.257+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:56 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%96%B9%E5%A4%A7%E5%90%8C/JTW%20%E8%A5%BF%E6%B8%B8%E8%AE%B0%20(Black)/85a8deb4-5c3c-47f8-a1ab-6f246d88d8d3.jpg' Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:20:56 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:56 j1800 volumio[5849]: info: CoreStateMachine::setRandom false Oct 14 12:20:56 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:56 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:56 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:56 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:56.916+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=30989 volume=38 Oct 14 12:20:56 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:56.916+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:57 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E5%AE%89%E6%A0%BC/%E4%B8%80%E4%B8%96%E6%83%85%E7%BC%98/19744dca-cd8f-4649-996a-f05f2dcade90.jpg' Oct 14 12:20:57 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E6%9D%B0/%E4%B8%80%E5%9C%BA%E6%B8%B8%E6%88%8F%E4%B8%80%E5%9C%BA%E6%A2%A6/64dc1ed3-cfbc-4563-a27e-318d3997a585.jpg' Oct 14 12:20:57 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:20:57 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:20:57 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:57 j1800 volumio[5849]: info: CoreStateMachine::setRandom true Oct 14 12:20:57 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:57 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:57 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:57 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:57 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:57.798+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=31716 volume=38 Oct 14 12:20:57 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:57.798+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:20:57 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%AD%E9%9D%99/%E4%B8%8B%E4%B8%80%E4%B8%AA%E5%A4%A9%E4%BA%AE/36449be0-aa14-4719-bc8d-580f4ee7e731.png' Oct 14 12:20:58 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B7%9D%E5%AD%90/%E4%BB%8A%E7%94%9F%E7%BC%98/5072104b-8578-4d9b-97dd-8c07510b66ea.jpg' Oct 14 12:20:58 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BE%9A%E7%8E%A5/%E4%BC%A0%E5%A5%87%E4%B9%8B%E5%A3%B0/a2202fb2-6772-4868-9c1b-938002e60c02.jpg' Oct 14 12:20:58 j1800 volumio[5849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BE%9A%E7%8E%A5/%E4%BC%A0%E5%A5%87%E4%B9%8B%E5%A3%B0%E2%85%A1/f2a672ff-4f9f-4be5-aeb8-df7a2cc05c9a.jpg' Oct 14 12:20:58 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:20:58 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:20:58 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:58 j1800 volumio[5849]: info: CoreStateMachine::setRandom false Oct 14 12:20:58 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:20:58 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:20:58 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:20:58 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:20:58 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:58.636+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=32710 volume=38 Oct 14 12:20:58 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:20:58.636+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:03 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:21:03 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:21:03 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:03 j1800 volumio[5849]: info: CoreStateMachine::setRandom true Oct 14 12:21:03 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:03 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:03 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:03 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:03 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:03.799+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=37873 volume=38 Oct 14 12:21:03 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:03.799+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:04 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:21:04 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:21:04 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:04 j1800 volumio[5849]: info: CoreStateMachine::setRandom false Oct 14 12:21:04 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:04 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:04 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:04 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:04 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:04.393+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=38451 volume=38 Oct 14 12:21:04 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:04.393+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:05 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:21:05 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:21:05 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:05 j1800 volumio[5849]: info: CoreStateMachine::setRepeat true single false Oct 14 12:21:05 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:05 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:05 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:05 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:05 j1800 nmbd[999]: [2026/10/14 12:21:05.340757, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 14 12:21:05 j1800 nmbd[999]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.81.248 for name WORKGROUP<1d>. Oct 14 12:21:05 j1800 nmbd[999]: This response was from IP 192.168.81.254, reporting an IP address of 192.168.81.254. Oct 14 12:21:05 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:05.343+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=39412 volume=38 Oct 14 12:21:05 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:05.343+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:05 j1800 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 14 12:21:06 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:21:06 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:21:06 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:06 j1800 volumio[5849]: info: CoreStateMachine::setRandom true Oct 14 12:21:06 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:06 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:06 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:06 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:06 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:06.252+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=40317 volume=38 Oct 14 12:21:06 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:06.253+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:06 j1800 systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 14 12:21:06 j1800 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 14 12:21:07 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:21:07 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:21:07 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:07 j1800 volumio[5849]: info: CoreStateMachine::setRepeat true single true Oct 14 12:21:07 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:07 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:07 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:07 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:07 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:07.416+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=41492 volume=38 Oct 14 12:21:07 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:07.416+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:09 j1800 volumio[5849]: info: CoreCommandRouter::volumioRandom Oct 14 12:21:09 j1800 volumio[5849]: info: CoreCommandRouter::writePlayerControls Oct 14 12:21:09 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:09 j1800 volumio[5849]: info: CoreStateMachine::setRepeat false single false Oct 14 12:21:09 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:09 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:09 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:09 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:09 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:09.122+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=43197 volume=38 Oct 14 12:21:09 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:09.122+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::volumioNext Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::next Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::stop Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::stPlaybackTimer Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::updateTrackBlock Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrackBlock Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::serviceStop Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrack 2 Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::serviceStop Oct 14 12:21:31 j1800 volumio[5849]: info: ControllerMpd::stop Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 12:21:31 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:31.954+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=0 volume=38 Oct 14 12:21:31 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:31.957+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/周虹 - 冷静.flac" title="周虹 - 冷静.flac" Oct 14 12:21:31 j1800 volumio[5849]: info: Oct 14 12:21:31 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand stop took 13 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::play index undefined Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::startPlaybackTimer Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/陈洁丽 - 等了又等.flac Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::updateTrackBlock Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrackBlock Oct 14 12:21:31 j1800 volumio[5849]: info: Oct 14 12:21:31 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:31 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:31 j1800 volumio[5849]: info: Oct 14 12:21:31 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:31 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand status took 17 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand stop took 7 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand status took 4 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand status took 4 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:31 j1800 volumio[5849]: info: Oct 14 12:21:31 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:21:31 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand clear took 4 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/陈洁丽 - 等了又等.flac" Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:31 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:31 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:31 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:31 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:31 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:32 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:32 j1800 volumio[5849]: 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":"周虹 - 冷静.flac","artist":null,"album":null,"uri":"NAS/NAS/周虹 - 冷静.flac","trackType":"flac"} Oct 14 12:21:32 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:32 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService stop Oct 14 12:21:32 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:21:32 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:32 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:32 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:32 j1800 volumio[5849]: info: No code Oct 14 12:21:32 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:32 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:32 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:32 j1800 volumio[5849]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 12:21:32 j1800 volumio[5849]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 12:21:32 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:32.014+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=0 volume=38 Oct 14 12:21:32 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:32.016+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=0 volume=38 Oct 14 12:21:32 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:32.016+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:32 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:32.015+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:32 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:32.017+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_STOPPED positionMs=0 volume=38 Oct 14 12:21:32 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:32.018+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 60ms Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 47ms Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 45ms Oct 14 12:21:32 j1800 volumio[5849]: info: Oct 14 12:21:32 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:21:32 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:21:32 j1800 volumio[5849]: info: Oct 14 12:21:32 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:21:32 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:21:32 j1800 volumio[5849]: error: updateQueue error: null Oct 14 12:21:32 j1800 volumio[5849]: info: Oct 14 12:21:32 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:21:32 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 40ms Oct 14 12:21:32 j1800 volumio[5849]: info: sendMpdCommand add "NAS/NAS/陈洁丽 - 等了又等.flac" took 37 milliseconds Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 3ms Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 2ms Oct 14 12:21:32 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand play Oct 14 12:21:32 j1800 volumio[5849]: info: Oct 14 12:21:32 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:21:32 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:21:32 j1800 volumio[5849]: info: Oct 14 12:21:32 j1800 volumio[5849]: ---------------------------- MPD announces system playlist update Oct 14 12:21:32 j1800 volumio[5849]: info: Ignoring MPD Status Update Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 10ms Oct 14 12:21:32 j1800 volumio[5849]: info: sendMpdCommand play took 9 milliseconds Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 8ms Oct 14 12:21:32 j1800 volumio[5849]: info: ------------------------------ 9ms Oct 14 12:21:34 j1800 volumio[5849]: info: Oct 14 12:21:34 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:34 j1800 volumio[5849]: info: Oct 14 12:21:34 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:34 j1800 volumio[5849]: info: Oct 14 12:21:34 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:34 j1800 volumio[5849]: info: Oct 14 12:21:34 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:34 j1800 volumio[5849]: info: Oct 14 12:21:34 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:34 j1800 volumio[5849]: info: Oct 14 12:21:34 j1800 volumio[5849]: ---------------------------- MPD announces state update: player Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand status took 16 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::getState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand status Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand status took 20 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand status took 19 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand status took 21 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand status took 21 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand status took 17 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseState Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"等了又等","artist":"陈洁丽","album":"鲍比达与陈洁丽","uri":"NAS/NAS/陈洁丽 - 等了又等.flac","trackType":"flac"} Oct 14 12:21:34 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus stop Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"501 Kbps","isStreaming":false,"title":"等了又等","artist":"陈洁丽","album":"鲍比达与陈洁丽","uri":"NAS/NAS/陈洁丽 - 等了又等.flac","trackType":"flac"} Oct 14 12:21:34 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:21:34 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"501 Kbps","isStreaming":false,"title":"等了又等","artist":"陈洁丽","album":"鲍比达与陈洁丽","uri":"NAS/NAS/陈洁丽 - 等了又等.flac","trackType":"flac"} Oct 14 12:21:34 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:21:34 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: ------------------------------ 74ms Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.241+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.241+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.252+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.252+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.258+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.259+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.261+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.262+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio[5849]: info: ------------------------------ 203ms Oct 14 12:21:34 j1800 volumio[5849]: info: ------------------------------ 202ms Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 170 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 170 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: info: sendMpdCommand playlistinfo took 170 milliseconds Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:34 j1800 volumio[5849]: verbose: ControllerMpd::parseTrackInfo Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"501 Kbps","isStreaming":false,"title":"等了又等","artist":"陈洁丽","album":"鲍比达与陈洁丽","uri":"NAS/NAS/陈洁丽 - 等了又等.flac","trackType":"flac"} Oct 14 12:21:34 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:21:34 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"501 Kbps","isStreaming":false,"title":"等了又等","artist":"陈洁丽","album":"鲍比达与陈洁丽","uri":"NAS/NAS/陈洁丽 - 等了又等.flac","trackType":"flac"} Oct 14 12:21:34 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:21:34 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: ControllerMpd::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::servicePushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"501 Kbps","isStreaming":false,"title":"等了又等","artist":"陈洁丽","album":"鲍比达与陈洁丽","uri":"NAS/NAS/陈洁丽 - 等了又等.flac","trackType":"flac"} Oct 14 12:21:34 j1800 volumio[5849]: verbose: CURRENT POSITION 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState stateService play Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::syncState currentStatus play Oct 14 12:21:34 j1800 volumio[5849]: info: Received an update from plugin. extracting info from payload Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio[5849]: info: CoreStateMachine::pushState Oct 14 12:21:34 j1800 volumio[5849]: info: CorePlayQueue::getTrack 1430 Oct 14 12:21:34 j1800 volumio[5849]: info: CoreCommandRouter::volumioPushState Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.390+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.390+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.391+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.392+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.393+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.393+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.395+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.395+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.397+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.397+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.398+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" state=STATUS_PLAYING positionMs=2600 volume=38 Oct 14 12:21:34 j1800 volumio5-onboarding[2216]: time=2026-10-14T12:21:34.398+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.81.104:60803 @ 0xc000348450" id="mnt/NAS/NAS/陈洁丽 - 等了又等.flac" title=等了又等 Oct 14 12:21:34 j1800 volumio[5849]: info: ------------------------------ 263ms Oct 14 12:21:34 j1800 volumio[5849]: info: ------------------------------ 263ms Oct 14 12:21:34 j1800 volumio[5849]: info: ------------------------------ 263ms Oct 14 12:21:45 j1800 volumio[5849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 12:21:45 j1800 volumio[5849]: Error: connect ETIMEDOUT 154.92.16.97:443 Oct 14 12:21:45 j1800 volumio[5849]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 14 12:21:45 j1800 volumio[5849]: errno: -110, Oct 14 12:21:45 j1800 volumio[5849]: code: 'ETIMEDOUT', Oct 14 12:21:45 j1800 volumio[5849]: syscall: 'connect', Oct 14 12:21:45 j1800 volumio[5849]: address: '154.92.16.97', Oct 14 12:21:45 j1800 volumio[5849]: port: 443 Oct 14 12:21:45 j1800 volumio[5849]: } Oct 14 12:21:45 j1800 volumio[5849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 12:21:45 j1800 sudo[6572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 12:20' Oct 14 12:21:45 j1800 sudo[6572]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"