Feb 25 00:08:02 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 00:08:03 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 25 00:08:03 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:03 volumio go-librespot[3357]: time="2026-02-25T00:08:03+09:00" level=trace msg="sent dealer ping" Feb 25 00:08:04 volumio go-librespot[3357]: time="2026-02-25T00:08:04+09:00" level=trace msg="received dealer pong" Feb 25 00:08:06 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 25 00:08:06 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:09 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 25 00:08:09 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:09 volumio volumio[1418]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::ClearQueue Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::stop Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::clearPlayQueue Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::saveQueue Feb 25 00:08:09 volumio volumio[1418]: info: CoreCommandRouter::volumioPushQueue Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::addQueueItems Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::addQueueItems Feb 25 00:08:09 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:09 volumio volumio[1418]: info: Adding Item to queue: https://zstream.win/pls/kbs_1fm.pls Feb 25 00:08:09 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 25 00:08:09 volumio volumio[1418]: info: CoreCommandRouter::volumioPushQueue Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::saveQueue Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::updateTrackBlock Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::getTrackBlock Feb 25 00:08:09 volumio volumio[1418]: info: CoreCommandRouter::volumioPlay Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::play index 0 Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::stop Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::play index undefined Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::startPlaybackTimer Feb 25 00:08:09 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:09 volumio volumio[1418]: info: [1771945689267] ControllerWebradio::clearAddPlayTrack Feb 25 00:08:09 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 00:08:09 volumio volumio[1418]: info: sendMpdCommand stop took 2 milliseconds Feb 25 00:08:09 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand clear Feb 25 00:08:09 volumio volumio[1418]: info: sendMpdCommand clear took 1 milliseconds Feb 25 00:08:09 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand load "https://zstream.win/pls/kbs_1fm.pls" Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: sendMpdCommand load "https://zstream.win/pls/kbs_1fm.pls" took 244 milliseconds Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 244ms Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 243ms Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 243ms Feb 25 00:08:09 volumio volumio[1418]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 25 00:08:09 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand play Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: Feb 25 00:08:09 volumio volumio[1418]: ---------------------------- MPD announces system playlist update Feb 25 00:08:09 volumio volumio[1418]: info: Ignoring MPD Status Update Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 6ms Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 5ms Feb 25 00:08:09 volumio volumio[1418]: info: sendMpdCommand play took 3 milliseconds Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 3ms Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 2ms Feb 25 00:08:09 volumio volumio[1418]: info: ------------------------------ 2ms Feb 25 00:08:09 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 25 00:08:09 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 20 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 20 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 19 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 19 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: Feb 25 00:08:10 volumio volumio[1418]: ---------------------------- MPD announces state update: player Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::getState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand status Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 4 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 2 milliseconds Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus stop Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":262,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":315,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 35ms Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.169+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.170+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.171+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 45ms Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 44ms Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 44ms Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 23 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand status took 22 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseState Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.195+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=22 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.196+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.196+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=22 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.196+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.196+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=22 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.196+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.201+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=22 volume=100 Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 51ms Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.203+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 50ms Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio sudo[5291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio sudo[5303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio sudo[5315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio sudo[5311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 25 00:08:10 volumio sudo[5289]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio sudo[5291]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 179 milliseconds Feb 25 00:08:10 volumio volumio[1418]: info: sendMpdCommand playlistinfo took 179 milliseconds Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: verbose: ControllerMpd::parseTrackInfo Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: ControllerMpd::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::servicePushState Feb 25 00:08:10 volumio volumio[1418]: info: CorePlayQueue::getTrack 0 Feb 25 00:08:10 volumio volumio[1418]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"194 Kbps","isStreaming":false,"title":"KBS 클래식FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__","trackType":""} Feb 25 00:08:10 volumio volumio[1418]: verbose: CURRENT POSITION 0 Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState stateService play Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::syncState currentStatus play Feb 25 00:08:10 volumio volumio[1418]: info: Received an update from plugin. extracting info from payload Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreStateMachine::pushState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioPushState Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.367+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=201 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.367+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.368+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=201 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.368+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.368+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=201 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.369+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.369+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" state=STATUS_PLAYING positionMs=201 volume=100 Feb 25 00:08:10 volumio volumio5-onboarding[1730]: time=2026-02-25T00:08:10.370+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:49446 @ 0x1f4c7e0" id="https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzIwOTcwMjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Sz3diYkDsvmkJAa-Z8vTC9PJgLRnnssultl0Cj3KJCkcFOqxyeeRB-aD1LU9Fz9jHUB79vrmykE3pcVAkDLIFjLxvmbrFr5dltIRKUO-iD7kNoZpX6aY3auVpy44Cx1UL7Jw7VzBLwgZL9Dm5jXqTUya7k1a8HiVMfkLXY8TRLAZ-~OT0xOH~WD-BaS-ltfnDXMcnhB7QoN~Elhmcz9-wmLGq8buBV1FXsYVfqJaxVYzypKPt-4oaN5mOkg~2XaYuunQu55WRegvG7~GD~sC6fKUmdmD9NJGqlLTG3cA3sL2gu2ppj~BpQQ02B0UcGxG-B9HNWWvfxJ1qg-enx7Vhg__" title="KBS 클래식FM" Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 217ms Feb 25 00:08:10 volumio volumio[1418]: info: ------------------------------ 217ms Feb 25 00:08:10 volumio sudo[5294]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5300]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio volumio[1418]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 25 00:08:10 volumio sudo[5325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio sudo[5297]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio sudo[5307]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio sudo[5323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio sudo[5303]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio sudo[5309]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio sudo[5315]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio sudo[5325]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio sudo[5311]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 25 00:08:10 volumio sudo[5326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio sudo[5323]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio sudo[5329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 25 00:08:10 volumio sudo[5329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio sudo[5326]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio sudo[5329]: pam_unix(sudo:session): session closed for user root Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: peppymeterbasic Daemon Started Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 00:08:10 volumio volumio[1418]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 25 00:08:10 volumio volumio[1418]: info: Received Get System Version Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 00:08:10 volumio volumio[1418]: info: Received Get System Info Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 00:08:10 volumio volumio[1418]: info: Discovery: Getting this device information Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::volumioGetState Feb 25 00:08:10 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 00:08:12 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Feb 25 00:08:12 volumio systemd[1]: peppymeterbasic.service: Consumed 1.526s CPU time. Feb 25 00:08:25 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Feb 25 00:08:25 volumio volumio[1418]: info: [ytmusic-browse] browseUri: ytmusic Feb 25 00:08:25 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:29 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Feb 25 00:08:29 volumio volumio[1418]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_library_landing%22%7D%7D Feb 25 00:08:31 volumio volumio[1418]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Feb 25 00:08:31 volumio volumio[1418]: info: [ytmusic] Innertube support service: Start service with Node Feb 25 00:08:32 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Feb 25 00:08:32 volumio volumio[1418]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_home%22%7D%7D Feb 25 00:08:33 volumio volumio[1418]: info: [ytmusic] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":37083}} Feb 25 00:08:33 volumio volumio[1418]: info: [ytmusic] Innertube support service running at http://127.0.0.1:37083 Feb 25 00:08:34 volumio go-librespot[3357]: time="2026-02-25T00:08:34+09:00" level=trace msg="sent dealer ping" Feb 25 00:08:34 volumio go-librespot[3357]: time="2026-02-25T00:08:34+09:00" level=trace msg="received dealer pong" Feb 25 00:08:37 volumio volumio[1418]: info: [ytmusic] Obtained session PO token using visitorData (expires in 43199 seconds) Feb 25 00:08:39 volumio volumio[1418]: info: [ytmusic] Going to refresh session PO token in 43099 seconds Feb 25 00:08:39 volumio volumio[1418]: error: You need to be signed in to access this content. You can do this in the plugin settings. Error: You need to be signed in to access this content. You can do this in the plugin settings. Feb 25 00:08:39 volumio volumio[1418]: at GenericViewHandler.browse (/data/plugins/music_service/ytmusic/dist/lib/controller/browse/view-handlers/GenericViewHandler.js:30:19) Feb 25 00:08:39 volumio volumio[1418]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 00:08:39 volumio volumio[1418]: at async BrowseController.browseUri (/data/plugins/music_service/ytmusic/dist/lib/controller/browse/BrowseController.js:30:20) Feb 25 00:08:39 volumio volumio[1418]: error: Failed to execute browseSource: Error: You need to be signed in to access this content. You can do this in the plugin settings. Feb 25 00:08:39 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:49 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 00:08:49 volumio volumio[1418]: info: In handleBrowseUri, curUri=spotify Feb 25 00:08:49 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:49 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:49 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:49 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:08:51 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 00:08:51 volumio volumio[1418]: info: In handleBrowseUri, curUri=spotify/myalbums Feb 25 00:08:52 volumio volumio[1418]: info: Preload queue cleared Feb 25 00:09:00 volumio volumio[1418]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 00:09:00 volumio volumio[1418]: info: In handleBrowseUri, curUri=spotify:album:5WNFvQycHNjxhcVwZHbYup Feb 25 00:09:01 volumio volumio[1418]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 00:09:01 volumio volumio[1418]: TypeError: Cannot read properties of undefined (reading 'url') Feb 25 00:09:01 volumio volumio[1418]: at /data/plugins/music_service/spop/index.js:2480:69 Feb 25 00:09:01 volumio volumio[1418]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 00:09:01 volumio volumio[1418]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 00:09:01 volumio sudo[5579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 00:08' Feb 25 00:09:01 volumio sudo[5579]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"