Mar 25 07:08:07 volumior volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 07:08:07 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 07:08:07 volumior sudo[3316]: volumio : unable to resolve host volumior: System error Mar 25 07:08:07 volumior sudo[3316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 07:08:07 volumior sudo[3316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 07:08:07 volumior systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 25 07:08:07 volumior sudo[3314]: volumio : unable to resolve host volumior: System error Mar 25 07:08:07 volumior sudo[3314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 07:08:07 volumior sudo[3314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 07:08:07 volumior sudo[3314]: pam_unix(sudo:session): session closed for user root Mar 25 07:08:07 volumior volumio[1245]: info: MPD Permissions set Mar 25 07:08:07 volumior systemd[1]: mpd.service: Deactivated successfully. Mar 25 07:08:07 volumior systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 25 07:08:07 volumior systemd[1]: mpd.service: Consumed 3.403s CPU time. Mar 25 07:08:07 volumior systemd[1]: mpd.socket: Deactivated successfully. Mar 25 07:08:07 volumior systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 25 07:08:07 volumior systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 25 07:08:07 volumior systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 25 07:08:07 volumior systemd[1]: Starting mpd.service - Music Player Daemon... Mar 25 07:08:07 volumior sudo[3325]: root : unable to resolve host volumior: System error Mar 25 07:08:07 volumior sudo[3325]: sudo: unable to resolve host volumior: System error Mar 25 07:08:07 volumior sudo[3325]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 07:08:07 volumior sudo[3325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 07:08:07 volumior sudo[3325]: pam_unix(sudo:session): session closed for user root Mar 25 07:08:07 volumior mpd[3327]: 2026-03-25T07:08:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 25 07:08:07 volumior systemd[1]: Started mpd.service - Music Player Daemon. Mar 25 07:08:07 volumior sudo[3316]: pam_unix(sudo:session): session closed for user root Mar 25 07:08:07 volumior volumio[1245]: error: updateQueue error: null Mar 25 07:08:16 volumior volumio[1245]: info: CoreCommandRouter::volumioStop Mar 25 07:08:16 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:16 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:21 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:21 volumior volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::ClearQueue Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::clearPlayQueue Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:21 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::addQueueItems Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::addQueueItems Mar 25 07:08:21 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:21 volumior volumio[1245]: info: Adding Item to queue: https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4 Mar 25 07:08:21 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 07:08:21 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::updateTrackBlock Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::getTrackBlock Mar 25 07:08:21 volumior volumio[1245]: info: CoreCommandRouter::volumioPlay Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::play index 0 Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::play index undefined Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::startPlaybackTimer Mar 25 07:08:21 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:21 volumior volumio[1245]: info: [1774390101282] ControllerWebradio::clearAddPlayTrack Mar 25 07:08:21 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 07:08:21 volumior volumio[1245]: info: sendMpdCommand stop took 1 milliseconds Mar 25 07:08:21 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 07:08:21 volumior volumio[1245]: info: Mar 25 07:08:21 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:21 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:21 volumior volumio[1245]: info: sendMpdCommand clear took 1 milliseconds Mar 25 07:08:21 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4" Mar 25 07:08:21 volumior volumio[1245]: error: updateQueue error: null Mar 25 07:08:21 volumior volumio[1245]: info: Mar 25 07:08:21 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:21 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:21 volumior volumio[1245]: info: ------------------------------ 1ms Mar 25 07:08:21 volumior volumio[1245]: info: sendMpdCommand add "https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4" took 1 milliseconds Mar 25 07:08:21 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 07:08:21 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Mar 25 07:08:21 volumior volumio[1245]: info: ------------------------------ 1ms Mar 25 07:08:21 volumior volumio[1245]: info: sendMpdCommand play took 1 milliseconds Mar 25 07:08:22 volumior volumio[1245]: info: Mar 25 07:08:22 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:22 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:22 volumior volumio[1245]: info: Mar 25 07:08:22 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:22 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:22 volumior volumio[1245]: error: MPD returned error for command status: Failed to decode "https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4"; got HTTP status 400 Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 07:08:22 volumior volumio[1245]: info: sendMpdCommand status took 3 milliseconds Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:22 volumior volumio[1245]: error: MPD returned error for command status: Failed to decode "https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4"; got HTTP status 400 Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 07:08:22 volumior volumio[1245]: info: sendMpdCommand status took 4 milliseconds Mar 25 07:08:22 volumior volumio[1245]: info: sendMpdCommand clearerror took 2 milliseconds Mar 25 07:08:22 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:22 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:22 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:22 volumior volumio[1245]: 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":"playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4","artist":null,"album":null,"uri":"https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4","trackType":""} Mar 25 07:08:22 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::syncState stateService stop Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:22 volumior volumio[1245]: info: No code Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.037+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=722 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.037+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=722 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.037+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.037+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=722 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.037+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.037+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=722 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.038+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.038+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.038+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=722 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.038+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=722 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.038+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.038+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio[1245]: info: ------------------------------ 16ms Mar 25 07:08:22 volumior volumio[1245]: info: sendMpdCommand clearerror took 15 milliseconds Mar 25 07:08:22 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 25 07:08:22 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:22 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:22 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:22 volumior volumio[1245]: 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":"playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4","artist":null,"album":null,"uri":"https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=60E1EF1583703EB4E947C5513961AF4CE5EE3775AE0CC2303FF030a496603296C3a4D3D23AC2F34260AA3CC183bEC17A827746DAEB449E304CD44BD18EF939FC751483CF1FEE429CDEAED8B778F29A6C705FFEE7DD9882098E77B35EB52D08D4A806E811F56B3AF025359E3A8C609FE4","trackType":""} Mar 25 07:08:22 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::syncState stateService stop Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:22 volumior volumio[1245]: info: No code Mar 25 07:08:22 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:22 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.053+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=763 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.053+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=763 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.054+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.054+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.054+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=763 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.054+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=763 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.055+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.055+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.055+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=763 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.055+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=763 volume=85 Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.056+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:22.056+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:22 volumior volumio[1245]: info: ------------------------------ 30ms Mar 25 07:08:28 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:28 volumior volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::ClearQueue Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::clearPlayQueue Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:28 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::addQueueItems Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::addQueueItems Mar 25 07:08:28 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:28 volumior volumio[1245]: info: Adding Item to queue: https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__ Mar 25 07:08:28 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 07:08:28 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::updateTrackBlock Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::getTrackBlock Mar 25 07:08:28 volumior volumio[1245]: info: CoreCommandRouter::volumioPlay Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::play index 0 Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::play index undefined Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::startPlaybackTimer Mar 25 07:08:28 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:28 volumior volumio[1245]: info: [1774390108825] ControllerWebradio::clearAddPlayTrack Mar 25 07:08:28 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 07:08:28 volumior volumio[1245]: info: sendMpdCommand stop took 1 milliseconds Mar 25 07:08:28 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 07:08:28 volumior volumio[1245]: info: Mar 25 07:08:28 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:28 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:28 volumior volumio[1245]: info: sendMpdCommand clear took 1 milliseconds Mar 25 07:08:28 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__" Mar 25 07:08:28 volumior volumio[1245]: error: updateQueue error: null Mar 25 07:08:28 volumior volumio[1245]: info: ------------------------------ 0ms Mar 25 07:08:28 volumior volumio[1245]: info: Mar 25 07:08:28 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:28 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:28 volumior volumio[1245]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__" took 0 milliseconds Mar 25 07:08:28 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 07:08:28 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Mar 25 07:08:28 volumior volumio[1245]: info: ------------------------------ 1ms Mar 25 07:08:28 volumior volumio[1245]: info: sendMpdCommand play took 1 milliseconds Mar 25 07:08:33 volumior volumio[1245]: info: Mar 25 07:08:33 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:33 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:33 volumior volumio[1245]: info: Mar 25 07:08:33 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:33 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:33 volumior volumio[1245]: error: MPD returned error for command status: Failed to decode "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQi; got HTTP status 403 Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 07:08:33 volumior volumio[1245]: info: sendMpdCommand status took 2 milliseconds Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:33 volumior volumio[1245]: error: MPD returned error for command status: Failed to decode "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQi; got HTTP status 403 Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 07:08:33 volumior volumio[1245]: info: sendMpdCommand status took 4 milliseconds Mar 25 07:08:33 volumior volumio[1245]: info: sendMpdCommand clearerror took 2 milliseconds Mar 25 07:08:33 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:33 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:33 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:33 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:33 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:33 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:33 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:33 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:33 volumior volumio[1245]: 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":"1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__","trackType":""} Mar 25 07:08:33 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:33 volumior volumio[1245]: info: CoreStateMachine::syncState stateService stop Mar 25 07:08:33 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Mar 25 07:08:33 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:33 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:33 volumior volumio[1245]: info: No code Mar 25 07:08:33 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:33 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.003+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=5160 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.003+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=5160 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.003+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.003+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=5160 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=5160 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=5160 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=5160 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.004+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio[1245]: info: ------------------------------ 16ms Mar 25 07:08:34 volumior volumio[1245]: info: sendMpdCommand clearerror took 14 milliseconds Mar 25 07:08:34 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 25 07:08:34 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:34 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:34 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:34 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:34 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:34 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:34 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:34 volumior volumio[1245]: 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":"1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzMDc0NzB9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=Ljde0okwWXfz~d2p1pZ-CDl4lJ4bKosszQ69d~VywBBceqgq1w33aeKVge6gQLr-5Uu8BqihKaivSl3xxFeuwxOhhUe6UZSLGkbke8nfvOrCgBpu8hjfsS9nSvHlOLjwp1jRsnArY5H~OfHypr9B~v9luwUFyNZ1gWnvv2ahcfKU-Ls5Rra2j1mvKnn3NEt~-b4CEsAXNRNAyh4RQSeFv0SNfZ8cneUzB4k3-kiNzai1xQikrcSSmjVSRU7J0KVSMkVT3KewPkbmdCcd9wiG4RHAd~tOpdPfQsUQXyyg221G8LEbcYADEfgQ1a9vrUM69Eyco5C1HQmK4QmcOE8SNg__","trackType":""} Mar 25 07:08:34 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:34 volumior volumio[1245]: info: CoreStateMachine::syncState stateService stop Mar 25 07:08:34 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Mar 25 07:08:34 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:34 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:34 volumior volumio[1245]: info: No code Mar 25 07:08:34 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:34 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=5184 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=5184 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=5184 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=5184 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.015+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.016+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.016+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=5184 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.016+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=5184 volume=85 Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.016+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:34.016+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:34 volumior volumio[1245]: info: ------------------------------ 27ms Mar 25 07:08:40 volumior volumio[1245]: info: CoreCommandRouter::volumioStop Mar 25 07:08:40 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:40 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:41 volumior volumio[1245]: info: CoreCommandRouter::volumioStop Mar 25 07:08:41 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:41 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:42 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:42 volumior volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::ClearQueue Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::clearPlayQueue Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:42 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::addQueueItems Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::addQueueItems Mar 25 07:08:42 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:42 volumior volumio[1245]: info: Adding Item to queue: https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 25 07:08:42 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 07:08:42 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::updateTrackBlock Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::getTrackBlock Mar 25 07:08:42 volumior volumio[1245]: info: CoreCommandRouter::volumioPlay Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::play index 0 Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::play index undefined Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::startPlaybackTimer Mar 25 07:08:42 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:42 volumior volumio[1245]: info: [1774390122645] ControllerWebradio::clearAddPlayTrack Mar 25 07:08:42 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 07:08:42 volumior volumio[1245]: info: sendMpdCommand stop took 3 milliseconds Mar 25 07:08:42 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 07:08:42 volumior volumio[1245]: info: Mar 25 07:08:42 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:42 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:42 volumior volumio[1245]: info: sendMpdCommand clear took 0 milliseconds Mar 25 07:08:42 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8" Mar 25 07:08:42 volumior volumio[1245]: error: updateQueue error: null Mar 25 07:08:42 volumior volumio[1245]: info: Mar 25 07:08:42 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:42 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:42 volumior volumio[1245]: info: ------------------------------ 1ms Mar 25 07:08:42 volumior volumio[1245]: info: sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8" took 1 milliseconds Mar 25 07:08:42 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 07:08:42 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Mar 25 07:08:42 volumior volumio[1245]: info: ------------------------------ 0ms Mar 25 07:08:42 volumior volumio[1245]: info: sendMpdCommand play took 0 milliseconds Mar 25 07:08:43 volumior volumio[1245]: info: Mar 25 07:08:43 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:43 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:43 volumior volumio[1245]: info: Mar 25 07:08:43 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:43 volumior volumio[1245]: info: sendMpdCommand status took 4 milliseconds Mar 25 07:08:43 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:43 volumior volumio[1245]: info: sendMpdCommand status took 1 milliseconds Mar 25 07:08:43 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:43 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:43 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:43 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:43 volumior volumio[1245]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Mar 25 07:08:43 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:43 volumior volumio[1245]: info: CoreStateMachine::syncState stateService play Mar 25 07:08:43 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Mar 25 07:08:43 volumior volumio[1245]: info: ------------------------------ 5ms Mar 25 07:08:43 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 07:08:43 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:43 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:43 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:43 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:43 volumior volumio[1245]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Mar 25 07:08:43 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:43 volumior volumio[1245]: info: CoreStateMachine::syncState stateService play Mar 25 07:08:43 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus play Mar 25 07:08:43 volumior volumio[1245]: info: Received an update from plugin. extracting info from payload Mar 25 07:08:43 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:43 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:43 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:43 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:43 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:43 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.223+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.223+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.224+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 title="CBS Music FM" Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.224+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 title="CBS Music FM" Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.225+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.225+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.225+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 title="CBS Music FM" Mar 25 07:08:43 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:43.225+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 title="CBS Music FM" Mar 25 07:08:43 volumior volumio[1245]: info: ------------------------------ 11ms Mar 25 07:08:45 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::ClearQueue Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::stPlaybackTimer Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::updateTrackBlock Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::getTrackBlock Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::serviceStop Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::serviceStop Mar 25 07:08:45 volumior volumio[1245]: info: [1774390125767] ControllerWebradio::stop Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::clearPlayQueue Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::addQueueItems Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::addQueueItems Mar 25 07:08:45 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:08:45 volumior volumio[1245]: info: Adding Item to queue: https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 07:08:45 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:45.769+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_STOPPED positionMs=0 volume=85 Mar 25 07:08:45 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:45.769+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_STOPPED positionMs=0 volume=85 Mar 25 07:08:45 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:45.769+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 title="CBS Music FM" Mar 25 07:08:45 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:45.770+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 title="CBS Music FM" Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::volumioPushQueue Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::saveQueue Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::updateTrackBlock Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::getTrackBlock Mar 25 07:08:45 volumior volumio[1245]: info: CoreCommandRouter::volumioPlay Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::play index 0 Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::stop Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::play index undefined Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::startPlaybackTimer Mar 25 07:08:45 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:45 volumior volumio[1245]: info: [1774390125774] ControllerWebradio::clearAddPlayTrack Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 07:08:45 volumior volumio[1245]: info: Mar 25 07:08:45 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand stop took 15 milliseconds Mar 25 07:08:45 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand stop took 8 milliseconds Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 07:08:45 volumior volumio[1245]: info: Mar 25 07:08:45 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:45 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand status took 1 milliseconds Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand clear took 1 milliseconds Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8" Mar 25 07:08:45 volumior volumio[1245]: error: updateQueue error: null Mar 25 07:08:45 volumior volumio[1245]: info: ------------------------------ 0ms Mar 25 07:08:45 volumior volumio[1245]: info: Mar 25 07:08:45 volumior volumio[1245]: ---------------------------- MPD announces system playlist update Mar 25 07:08:45 volumior volumio[1245]: info: Ignoring MPD Status Update Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8" took 1 milliseconds Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:45 volumior volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 07:08:45 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Mar 25 07:08:45 volumior volumio[1245]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 07:08:45 volumior volumio[1245]: info: ------------------------------ 2ms Mar 25 07:08:45 volumior volumio[1245]: info: ------------------------------ 1ms Mar 25 07:08:45 volumior volumio[1245]: info: sendMpdCommand play took 0 milliseconds Mar 25 07:08:46 volumior volumio[1245]: info: Mar 25 07:08:46 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:46 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:46 volumior volumio[1245]: info: Mar 25 07:08:46 volumior volumio[1245]: ---------------------------- MPD announces state update: player Mar 25 07:08:46 volumior volumio[1245]: info: sendMpdCommand status took 4 milliseconds Mar 25 07:08:46 volumior volumio[1245]: info: ControllerMpd::getState Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:46 volumior volumio[1245]: info: sendMpdCommand status took 0 milliseconds Mar 25 07:08:46 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::parseState Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:46 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:46 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:46 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:46 volumior volumio[1245]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8","trackType":"m3u8"} Mar 25 07:08:46 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:46 volumior volumio[1245]: info: CoreStateMachine::syncState stateService play Mar 25 07:08:46 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Mar 25 07:08:46 volumior volumio[1245]: info: ------------------------------ 6ms Mar 25 07:08:46 volumior volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 07:08:46 volumior volumio[1245]: verbose: ControllerMpd::parseTrackInfo Mar 25 07:08:46 volumior volumio[1245]: info: ControllerMpd::pushState Mar 25 07:08:46 volumior volumio[1245]: info: CoreCommandRouter::servicePushState Mar 25 07:08:46 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:46 volumior volumio[1245]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8","trackType":"m3u8"} Mar 25 07:08:46 volumior volumio[1245]: verbose: CURRENT POSITION 0 Mar 25 07:08:46 volumior volumio[1245]: info: CoreStateMachine::syncState stateService play Mar 25 07:08:46 volumior volumio[1245]: info: CoreStateMachine::syncState currentStatus play Mar 25 07:08:46 volumior volumio[1245]: info: Received an update from plugin. extracting info from payload Mar 25 07:08:46 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:46 volumior volumio[1245]: info: CorePlayQueue::getTrack 0 Mar 25 07:08:46 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 07:08:46 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:46 volumior volumio[1245]: info: CoreStateMachine::pushState Mar 25 07:08:46 volumior volumio[1245]: info: CoreCommandRouter::volumioPushState Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.243+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.243+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.244+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.244+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.244+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" state=STATUS_PLAYING positionMs=0 volume=85 Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.244+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.136:50588 @ 0x2fe99e0" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.244+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:46 volumior volumio5-onboarding[1478]: time=2026-03-25T07:08:46.244+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.0.136:50588 @ 0x2eb1920" id=https://m-aac.cbs.co.kr/mweb_cbscmc/_definst_/cbscmc.stream/playlist.m3u8 title="CBS JOY4U" Mar 25 07:08:46 volumior volumio[1245]: info: ------------------------------ 10ms Mar 25 07:09:10 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 25 07:09:10 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:09:13 volumior volumio[1245]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 25 07:09:13 volumior volumio[1245]: info: Preload queue cleared Mar 25 07:09:13 volumior volumio[1245]: info: Preloading song: websbs/0 Mar 25 07:09:13 volumior volumio[1245]: info: Preloading song: websbs/1 Mar 25 07:09:13 volumior volumio[1245]: info: Preloading song: websbs/2 Mar 25 07:09:13 volumior volumio[1245]: info: Exploding uri websbs/0 in service personal_radio Mar 25 07:09:13 volumior volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 07:09:13 volumior volumio[1245]: TypeError: Cannot read properties of null (reading 'startsWith') Mar 25 07:09:13 volumior volumio[1245]: at /data/plugins/music_service/personal_radio/index.js:675:41 Mar 25 07:09:13 volumior volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Mar 25 07:09:13 volumior volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 07:09:18 volumior sudo[3589]: volumio : unable to resolve host volumior: System error Mar 25 07:09:18 volumior sudo[3589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 07:08' Mar 25 07:09:18 volumior sudo[3589]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"