Apr 10 20:17:00 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:00.957+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Apr 10 20:17:00 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:00.958+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2001710" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:00 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:00.958+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%15 @ 0x2336cc0" state=STATUS_PLAYING positionMs= volume=51 Apr 10 20:17:00 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:00.958+09:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Apr 10 20:17:03 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 10 20:17:03 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:03.664+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=21 chunks=1 index=0 tries=11 Apr 10 20:17:03 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:03.665+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%15 @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:03 volumio volumio[1313]: info: Preload queue cleared Apr 10 20:17:04 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:04.266+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Apr 10 20:17:04 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:04.267+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2001710" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:04 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:04.267+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%15 @ 0x2336cc0" state=STATUS_PLAYING positionMs= volume=51 Apr 10 20:17:04 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:04.267+09:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Apr 10 20:17:09 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:09.378+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Apr 10 20:17:09 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:09.379+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2001710" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:09 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:09.379+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%15 @ 0x2336cc0" state=STATUS_PLAYING positionMs= volume=51 Apr 10 20:17:09 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:09.379+09:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Apr 10 20:17:13 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:13.890+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Apr 10 20:17:13 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:13.890+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2001710" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:13 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:13.890+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%15 @ 0x2336cc0" state=STATUS_PLAYING positionMs= volume=51 Apr 10 20:17:13 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:13.890+09:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Apr 10 20:17:15 volumio volumio[1313]: info: Saving local item radio-favourites Apr 10 20:17:15 volumio volumio[1313]: info: Pushing Favourites {"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","favourite":false} Apr 10 20:17:17 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:17.499+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=21 chunks=1 index=0 tries=11 Apr 10 20:17:17 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:17.499+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Apr 10 20:17:17 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:17.500+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2001710" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:17 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:17.500+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%15 @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:17 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:17.500+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%15 @ 0x2336cc0" state=STATUS_PLAYING positionMs= volume=51 Apr 10 20:17:17 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:17.500+09:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Apr 10 20:17:20 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:20.808+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=21 chunks=1 index=0 tries=11 Apr 10 20:17:20 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:20.808+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%15 @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:20 volumio volumio[1313]: error: error Apr 10 20:17:24 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:24.118+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=21 chunks=1 index=0 tries=11 Apr 10 20:17:24 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:24.118+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%15 @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:27 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:27.428+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=21 chunks=1 index=0 tries=11 Apr 10 20:17:27 volumio volumio5-onboarding[1664]: time=2026-04-10T20:17:27.428+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%15 @ 0x2336cc0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioSeek Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::seek Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: TRACKBLOCK {"service":"webradio","type":"mywebradio","artist":"","album":"","title":"CBS FM","uri":"http://serpent0.duckdns.org:8088/cbsfm.pls","icon":"fa fa-microphone","name":"CBS FM","albumart":"/albumart","samplerate":"","bitdepth":"","channels":0,"trackType":"webradio"} Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: [1775819853382] ControllerWebradio::seek Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::seek Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: error: [5@0] {seek} Decoder failed to seek Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:33 volumio volumio[1313]: error: MPD returned error for command status: Decoder failed to seek Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand status took 9 milliseconds Apr 10 20:17:33 volumio volumio[1313]: error: MPD returned error for command status: Decoder failed to seek Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand status took 9 milliseconds Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:33 volumio volumio[1313]: error: MPD returned error for command status: Decoder failed to seek Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand status took 15 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand clearerror took 10 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand clearerror took 9 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand status took 5 milliseconds Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: 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":"CBS Music FM","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Apr 10 20:17:33 volumio volumio[1313]: verbose: CURRENT POSITION 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::syncState stateService stop Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::play index undefined Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: [1775819853444] ControllerWebradio::clearAddPlayTrack Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 45ms Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 44ms Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand clearerror took 42 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 41 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand stop took 16 milliseconds Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand clear Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 10 20:17:33 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:33 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:33 volumio volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 72ms Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 62ms Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces system playlist update Apr 10 20:17:33 volumio volumio[1313]: info: Ignoring MPD Status Update Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand clear took 15 milliseconds Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/cbsfm.pls" Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces system playlist update Apr 10 20:17:33 volumio volumio[1313]: info: Ignoring MPD Status Update Apr 10 20:17:33 volumio volumio[1313]: error: updateQueue error: null Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 5ms Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces system playlist update Apr 10 20:17:33 volumio volumio[1313]: info: Ignoring MPD Status Update Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/cbsfm.pls" took 32 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 20:17:33 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand play Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 29ms Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 1ms Apr 10 20:17:33 volumio volumio[1313]: info: sendMpdCommand play took 1 milliseconds Apr 10 20:17:33 volumio volumio[1313]: info: Apr 10 20:17:33 volumio volumio[1313]: ---------------------------- MPD announces system playlist update Apr 10 20:17:33 volumio volumio[1313]: info: Ignoring MPD Status Update Apr 10 20:17:33 volumio volumio[1313]: info: ------------------------------ 1ms Apr 10 20:17:34 volumio volumio[1313]: info: Apr 10 20:17:34 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:34 volumio volumio[1313]: info: Apr 10 20:17:34 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand status took 18 milliseconds Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:34 volumio volumio[1313]: info: Apr 10 20:17:34 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:34 volumio volumio[1313]: info: Apr 10 20:17:34 volumio volumio[1313]: ---------------------------- MPD announces state update: player Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::getState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand status took 26 milliseconds Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand status took 4 milliseconds Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:34 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:34 volumio volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS Music FM","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Apr 10 20:17:34 volumio volumio[1313]: verbose: CURRENT POSITION 0 Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState stateService play Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Apr 10 20:17:34 volumio volumio[1313]: info: ------------------------------ 35ms Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand status took 7 milliseconds Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseState Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:34 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:34 volumio volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS Music FM","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Apr 10 20:17:34 volumio volumio[1313]: verbose: CURRENT POSITION 0 Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState stateService play Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:17:34 volumio volumio[1313]: info: Received an update from plugin. extracting info from payload Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:34 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:34 volumio volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS Music FM","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Apr 10 20:17:34 volumio volumio[1313]: verbose: CURRENT POSITION 0 Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState stateService play Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:17:34 volumio volumio[1313]: info: Received an update from plugin. extracting info from payload Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:34 volumio volumio[1313]: info: ------------------------------ 67ms Apr 10 20:17:34 volumio volumio[1313]: info: ------------------------------ 47ms Apr 10 20:17:34 volumio volumio[1313]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 10 20:17:34 volumio volumio[1313]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:17:34 volumio volumio[1313]: info: ControllerMpd::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Apr 10 20:17:34 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Apr 10 20:17:34 volumio volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS Music FM","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Apr 10 20:17:34 volumio volumio[1313]: verbose: CURRENT POSITION 0 Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState stateService play Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:17:34 volumio volumio[1313]: info: Received an update from plugin. extracting info from payload Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreStateMachine::pushState Apr 10 20:17:34 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Apr 10 20:17:34 volumio volumio[1313]: info: ------------------------------ 61ms Apr 10 20:18:18 volumio volumio[1313]: info: Preload queue cleared Apr 10 20:18:22 volumio volumio[1313]: error: error Apr 10 20:18:30 volumio volumio[1313]: info: Saving local item radio-favourites Apr 10 20:18:30 volumio volumio[1313]: info: Pushing Favourites {"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","favourite":false} Apr 10 20:18:41 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 10 20:18:41 volumio volumio[1313]: info: CURURI: artists:// Apr 10 20:18:41 volumio volumio[1313]: info: Preload queue cleared Apr 10 20:18:43 volumio volumio[1313]: info: Preload queue cleared Apr 10 20:18:44 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 10 20:18:45 volumio volumio[1313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 20:18:45 volumio volumio[1313]: AggregateError Apr 10 20:18:45 volumio volumio[1313]: at internalConnectMultiple (node:net:1114:18) Apr 10 20:18:45 volumio volumio[1313]: at afterConnectMultiple (node:net:1667:5) { Apr 10 20:18:45 volumio volumio[1313]: code: 'ETIMEDOUT', Apr 10 20:18:45 volumio volumio[1313]: [errors]: [ Apr 10 20:18:45 volumio volumio[1313]: Error: connect ETIMEDOUT 158.69.209.125:443 Apr 10 20:18:45 volumio volumio[1313]: at createConnectionError (node:net:1634:14) Apr 10 20:18:45 volumio volumio[1313]: at Timeout.internalConnectMultipleTimeout (node:net:1685:38) Apr 10 20:18:45 volumio volumio[1313]: at listOnTimeout (node:internal/timers:575:11) Apr 10 20:18:45 volumio volumio[1313]: at process.processTimers (node:internal/timers:514:7) { Apr 10 20:18:45 volumio volumio[1313]: errno: -110, Apr 10 20:18:45 volumio volumio[1313]: code: 'ETIMEDOUT', Apr 10 20:18:45 volumio volumio[1313]: syscall: 'connect', Apr 10 20:18:45 volumio volumio[1313]: address: '158.69.209.125', Apr 10 20:18:45 volumio volumio[1313]: port: 443 Apr 10 20:18:45 volumio volumio[1313]: }, Apr 10 20:18:45 volumio volumio[1313]: Error: connect ECONNREFUSED 158.69.210.98:443 Apr 10 20:18:45 volumio volumio[1313]: at createConnectionError (node:net:1634:14) Apr 10 20:18:45 volumio volumio[1313]: at afterConnectMultiple (node:net:1664:40) { Apr 10 20:18:45 volumio volumio[1313]: errno: -111, Apr 10 20:18:45 volumio volumio[1313]: code: 'ECONNREFUSED', Apr 10 20:18:45 volumio volumio[1313]: syscall: 'connect', Apr 10 20:18:45 volumio volumio[1313]: address: '158.69.210.98', Apr 10 20:18:45 volumio volumio[1313]: port: 443 Apr 10 20:18:45 volumio volumio[1313]: } Apr 10 20:18:45 volumio volumio[1313]: ] Apr 10 20:18:45 volumio volumio[1313]: } Apr 10 20:18:45 volumio volumio[1313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 20:18:46 volumio sudo[4325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-10 20:17' Apr 10 20:18:46 volumio sudo[4325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"