Apr 02 21:11:00 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:00 volumio volumio[1058]: info: CorePlayQueue::saveQueue Apr 02 21:11:00 volumio volumio[1058]: info: CoreStateMachine::updateTrackBlock Apr 02 21:11:00 volumio volumio[1058]: info: CorePlayQueue::getTrackBlock Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.60:8200/MediaItems/877.flac" Apr 02 21:11:01 volumio volumio[1058]: error: updateQueue error: null Apr 02 21:11:01 volumio volumio[1058]: error: updateQueue error: null Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:01 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 2172ms Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 2171ms Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand add "http://192.168.50.60:8200/MediaItems/877.flac" took 2 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand play Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:01 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:01 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 5ms Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand play took 4 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 3ms Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 3ms Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand status took 4 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:01 volumio volumio[1058]: info: Apr 02 21:11:01 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand status took 6 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand status took 5 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand status took 4 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand status took 2 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand status took 1 milliseconds Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:01 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:01 volumio volumio[1058]: verbose: CURRENT POSITION 4 Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 16ms Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 21:11:01 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:01 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:01 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1095 Kbps","isStreaming":false,"title":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:01 volumio volumio[1058]: verbose: CURRENT POSITION 4 Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:01 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:01 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1105 Kbps","isStreaming":false,"title":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:01 volumio volumio[1058]: verbose: CURRENT POSITION 4 Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:01 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:01 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1105 Kbps","isStreaming":false,"title":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:01 volumio volumio[1058]: verbose: CURRENT POSITION 4 Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:01 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:01 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1105 Kbps","isStreaming":false,"title":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:01 volumio volumio[1058]: verbose: CURRENT POSITION 4 Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:01 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:01 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:01 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1105 Kbps","isStreaming":false,"title":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:01 volumio volumio[1058]: verbose: CURRENT POSITION 4 Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:01 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:01 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.904+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.904+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.904+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.904+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.905+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.905+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.906+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.906+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.907+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.907+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.908+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.908+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.909+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.911+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.911+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.911+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.914+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.914+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.915+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:01.915+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=877.flac Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 82ms Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 81ms Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 83ms Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 80ms Apr 02 21:11:01 volumio volumio[1058]: info: ------------------------------ 80ms Apr 02 21:11:13 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:13 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:14 volumio volumio[1058]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AGA/Ginadoll%20Concert%20Live/c67264ee-e1be-4fa1-8511-050ccdec8ee8.jpg' Apr 02 21:11:14 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:14 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:14 volumio volumio[1058]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/106/82fb6cf8-9900-40d7-9b6b-05a2ee1cc3fb.jpg' Apr 02 21:11:14 volumio volumio[1058]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/107/184d5fa0-9463-4c9d-b76d-56b97d7d0de7.jpg' Apr 02 21:11:14 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:14 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:14 volumio volumio[1058]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/115/0c177bf1-7a47-48ad-a1d9-2bc63f0420e7.jpg' Apr 02 21:11:15 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:15 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:20 volumio volumio[1058]: info: CoreCommandRouter::volumioMoveQueue Apr 02 21:11:20 volumio volumio[1058]: info: CoreStateMachine::moveQueueItem 72 72 Apr 02 21:11:20 volumio volumio[1058]: info: CorePlayQueue::moveQueueItem 72 --> 72 Apr 02 21:11:20 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:23 volumio volumio[1058]: info: CoreCommandRouter::volumioMoveQueue Apr 02 21:11:23 volumio volumio[1058]: info: CoreStateMachine::moveQueueItem 38 38 Apr 02 21:11:23 volumio volumio[1058]: info: CorePlayQueue::moveQueueItem 38 --> 38 Apr 02 21:11:23 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:23 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:11:24 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:24 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:24 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:11:24 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:11:25 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:11:25 volumio volumio[1058]: info: CoreCommandRouter::volumioMoveQueue Apr 02 21:11:25 volumio volumio[1058]: info: CoreStateMachine::moveQueueItem 56 56 Apr 02 21:11:25 volumio volumio[1058]: info: CorePlayQueue::moveQueueItem 56 --> 56 Apr 02 21:11:25 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:32 volumio volumio[1058]: info: CoreCommandRouter::volumioMoveQueue Apr 02 21:11:32 volumio volumio[1058]: info: CoreStateMachine::moveQueueItem 70 70 Apr 02 21:11:32 volumio volumio[1058]: info: CorePlayQueue::moveQueueItem 70 --> 70 Apr 02 21:11:32 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:34 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:11:35 volumio volumio[1058]: info: CoreCommandRouter::volumioMoveQueue Apr 02 21:11:35 volumio volumio[1058]: info: CoreStateMachine::moveQueueItem 12 12 Apr 02 21:11:35 volumio volumio[1058]: info: CorePlayQueue::moveQueueItem 12 --> 12 Apr 02 21:11:35 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:45 volumio volumio[1058]: info: CoreCommandRouter::volumioMoveQueue Apr 02 21:11:45 volumio volumio[1058]: info: CoreStateMachine::moveQueueItem 709 709 Apr 02 21:11:45 volumio volumio[1058]: info: CorePlayQueue::moveQueueItem 709 --> 709 Apr 02 21:11:45 volumio volumio[1058]: info: CoreCommandRouter::volumioPushQueue Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPlay Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::play index 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::stop Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::stPlaybackTimer Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::updateTrackBlock Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrackBlock Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::serviceStop Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 4 Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::serviceStop Apr 02 21:11:51 volumio volumio[1058]: info: [1775135511460] ControllerUPNPBrowser::stop Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.464+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_STOPPED positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.464+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/877.flac title=03.程响-可能 Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand stop took 12 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::play index undefined Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::startPlaybackTimer Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: info: [1775135511474] ControllerUPNPBrowser::clearAddPlayTrack Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand stop took 8 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 5 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 3 milliseconds Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:51 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand clear took 2 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.60:8200/MediaItems/1019.flac" Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: 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":"877.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/877.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: No code Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 02 21:11:51 volumio volumio[1058]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.515+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_STOPPED positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.515+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=117.徐佳莹-一样的月光 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.517+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_STOPPED positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.517+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_STOPPED positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.517+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=117.徐佳莹-一样的月光 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.517+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=117.徐佳莹-一样的月光 Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 48ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 45ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 42ms Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:51 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:51 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:51 volumio volumio[1058]: error: updateQueue error: null Apr 02 21:11:51 volumio volumio[1058]: error: updateQueue error: null Apr 02 21:11:51 volumio volumio[1058]: error: updateQueue error: null Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 38ms Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.60:8200/MediaItems/1019.flac" Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 3ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 3ms Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:51 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand add "http://192.168.50.60:8200/MediaItems/1019.flac" took 12 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand play Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:51 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces system playlist update Apr 02 21:11:51 volumio volumio[1058]: info: Ignoring MPD Status Update Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 7ms Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand play took 5 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 4ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 4ms Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 9 milliseconds Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1019.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/1019.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1019.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/1019.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:51 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1019.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/1019.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:51 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 75ms Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.642+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.643+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.643+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.643+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.643+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.644+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.644+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=0 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.644+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 100ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 101ms Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: Apr 02 21:11:51 volumio volumio[1058]: ---------------------------- MPD announces state update: player Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::getState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 82 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 3 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand status took 1 milliseconds Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseState Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 21:11:51 volumio volumio[1058]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: verbose: ControllerMpd::parseTrackInfo Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1633 Kbps","isStreaming":false,"title":"1019.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/1019.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:51 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1633 Kbps","isStreaming":false,"title":"1019.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/1019.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:51 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: ControllerMpd::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::servicePushState Apr 02 21:11:51 volumio volumio[1058]: info: CorePlayQueue::getTrack 44 Apr 02 21:11:51 volumio volumio[1058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1633 Kbps","isStreaming":false,"title":"1019.flac","artist":null,"album":null,"uri":"http://192.168.50.60:8200/MediaItems/1019.flac","trackType":"flac"} Apr 02 21:11:51 volumio volumio[1058]: verbose: CURRENT POSITION 44 Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 02 21:11:51 volumio volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreStateMachine::pushState Apr 02 21:11:51 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.739+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=180 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.739+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.740+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=180 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=180 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.744+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.742+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=180 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.744+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.742+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.746+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=180 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.746+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.747+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" state=STATUS_PLAYING positionMs=180 volume=100 Apr 02 21:11:51 volumio volumio5-onboarding[1934]: time=2026-04-02T21:11:51.747+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.112:39030 @ 0xc0001f1980" id=http://192.168.50.60:8200/MediaItems/1019.flac title=1019.flac Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 182ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 101ms Apr 02 21:11:51 volumio volumio[1058]: info: ------------------------------ 100ms Apr 02 21:11:53 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:53 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:54 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:54 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:54 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:54 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:11:55 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:11:55 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:12:03 volumio volumio5-onboarding[1934]: time=2026-04-02T21:12:03.668+08:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.50.112:39030 error="read tcp 192.168.50.140:7331->192.168.50.112:39030: read: connection reset by peer" Apr 02 21:12:03 volumio volumio5-onboarding[1934]: time=2026-04-02T21:12:03.669+08:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.50.112:39030 Apr 02 21:12:03 volumio volumio5-onboarding[1934]: time=2026-04-02T21:12:03.669+08:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.50.112:39030 Apr 02 21:12:03 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:12:04 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:12:04 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:12:04 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:12:04 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:12:05 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:12:14 volumio volumio[1058]: info: MyVolumio login type: Token Apr 02 21:12:27 volumio ntpd[945]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 02 21:12:33 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:12:33 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:12:34 volumio nmbd[1040]: [2026/04/02 21:12:34.247696, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 02 21:12:34 volumio nmbd[1040]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.50.140 for name WORKGROUP<1d>. Apr 02 21:12:34 volumio nmbd[1040]: This response was from IP 192.168.50.60, reporting an IP address of 192.168.50.60. Apr 02 21:12:34 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:12:34 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:12:34 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:12:34 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:12:35 volumio volumio[1058]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 02 21:12:35 volumio volumio[1058]: info: Retrying Login Due to Network Error Apr 02 21:12:42 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 21:12:42 volumio volumio[1058]: Error: connect ETIMEDOUT 69.171.242.11:443 Apr 02 21:12:42 volumio volumio[1058]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 02 21:12:42 volumio volumio[1058]: errno: -110, Apr 02 21:12:42 volumio volumio[1058]: code: 'ETIMEDOUT', Apr 02 21:12:42 volumio volumio[1058]: syscall: 'connect', Apr 02 21:12:42 volumio volumio[1058]: address: '69.171.242.11', Apr 02 21:12:42 volumio volumio[1058]: port: 443 Apr 02 21:12:42 volumio volumio[1058]: } Apr 02 21:12:42 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 21:12:42 volumio sudo[2996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-02 21:11' Apr 02 21:12:42 volumio sudo[2996]: 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"