-- Logs begin at Wed 2026-03-25 17:00:51 KST, end at Thu 2026-03-26 09:07:42 KST. -- Mar 26 09:06:10 adv-volumio volumio[17267]: info: [fetchProgramInfo] https://radio.yuntae.in/api/onair/program?stn=kbs&ch=1fm -> 가정음악 Mar 26 09:06:10 adv-volumio volumio[17267]: info: [programRefresh] fetched: 가정음악 Mar 26 09:06:10 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:10 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:10 adv-volumio volumio[17267]: info: sendMpdCommand status took 2 milliseconds Mar 26 09:06:10 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:10 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:10 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 09:06:10 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:10 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:10 adv-volumio volumio[17267]: info: [programRefresh] queue updated Mar 26 09:06:10 adv-volumio volumio[17267]: info: [programRefresh] pushed: status=play program=가정음악 Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:10 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:10 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120729,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"165 Kbps","isStreaming":false,"title":"KBS ClassicFM","artist":"가정음악","album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTQ2MTZ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=dwIgBNbtGa4kETVVxlQ0hGwrHQkKi41rJCarrFUdTZpwqSR3PA99QT2F7mRXwws7cWVQZXhb9FDneYGGZS84IvIJdHSgXIGgwyM3pKmQDxkfWLAxGXmakNZdf65-6WXxg-58t9p4RXa9C6Mp1I~gZxCrz8EcWvXUQi0B5NW9AasaBllTmitCyuo5fhNy70sjjhp9MNBEvyju40XnF34VSoMZZ720PbDnduJ7XxrguK1LG2ZhhwV93595I5HXQdBQD0~yS7ZhGH48JyeF3~ONFY5-wcI4DrkwR4fY3lkdt4eJegvVMDw1NlePgQHXNe1bga6GVBub5EGBrVyLmFB~3A__","trackType":"","service":"carrot_radio","albumart":"https://padmin.static.kbs.co.kr/live/2021/5/28/1622175310040_252323.jpg","disableVolumioPause":true} Mar 26 09:06:10 adv-volumio volumio[17267]: verbose: CURRENT POSITION 4 Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus play Mar 26 09:06:10 adv-volumio volumio[17267]: info: Received an update from plugin. extracting info from payload Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:10 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:10 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:10 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPlay Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::play index 7 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::stPlaybackTimer Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::serviceStop Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::serviceStop Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand stop took 38 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"stop","service":"carrot_radio","title":"","artist":"","album":"","albumart":"/albumart?sourceicon=music_service/carrot_radio/logos/carrot.png","uri":"","duration":0,"trackType":"webradio","disableVolumioPause":true} Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: CURRENT POSITION 4 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: No code Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 4 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::play index undefined Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::startPlaybackTimer Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:36 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:36 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand status took 43 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand stop took 6 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand status took 4 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand status took 2 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand clear took 8 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8" Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:36 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:36 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:36 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:36 adv-volumio volumio[17267]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 26 09:06:36 adv-volumio volumio[17267]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 77ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 38ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 36ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:36 adv-volumio volumio[17267]: error: updateQueue error: null Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 29ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8" took 26 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 6ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 4ms Mar 26 09:06:36 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand play Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Mar 26 09:06:36 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:36 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 16ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: sendMpdCommand play took 8 milliseconds Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 8ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: ------------------------------ 6ms Mar 26 09:06:36 adv-volumio volumio[17267]: info: [clearAddPlayTrack] title=CBS 음악FM program=yes Mar 26 09:06:36 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:36 adv-volumio volumio[17267]: info: [startProgramRefresh] CBS 음악FM Mar 26 09:06:36 adv-volumio volumio[17267]: info: Pushing Favourites {"uri":"webcarrot/19","favourite":false} Mar 26 09:06:37 adv-volumio volumio[17267]: info: [fetchProgramInfo] https://station.zstream.win/api/onair/program?stn=cbs&ch=musicfm -> 김정원의 아름다운 당신에게 Mar 26 09:06:37 adv-volumio volumio[17267]: info: [programRefresh] fetched: 김정원의 아름다운 당신에게 Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 3 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: [programRefresh] queue updated Mar 26 09:06:37 adv-volumio volumio[17267]: info: [programRefresh] pushed: status=play program=김정원의 아름다운 당신에게 Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS 음악FM","artist":"김정원의 아름다운 당신에게","album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8","service":"carrot_radio","albumart":"https://static.mytuner.mobi/media/tvos_radios/593/eumagfm-cbs-radio-music-fm.0f29a25a.png","disableVolumioPause":true} Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: CURRENT POSITION 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:37 adv-volumio volumio[17267]: info: Mar 26 09:06:37 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: Mar 26 09:06:37 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: Mar 26 09:06:37 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: Mar 26 09:06:37 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 28 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 26 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 27 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 25 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 10 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 8 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: [programRefresh] ICY override -> status=play program=김정원의 아름다운 당신에게 Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:37 adv-volumio volumio[17267]: info: ------------------------------ 53ms Mar 26 09:06:37 adv-volumio volumio[17267]: info: Mar 26 09:06:37 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: Mar 26 09:06:37 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 8 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand status took 5 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:37 adv-volumio volumio[17267]: info: [programRefresh] ICY override -> status=play program=김정원의 아름다운 당신에게 Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:37 adv-volumio volumio[17267]: info: ------------------------------ 82ms Mar 26 09:06:37 adv-volumio volumio[17267]: info: ------------------------------ 77ms Mar 26 09:06:37 adv-volumio volumio[17267]: info: ------------------------------ 64ms Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:37 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 09:06:37 adv-volumio volumio[17267]: info: ------------------------------ 43ms Mar 26 09:06:37 adv-volumio volumio[17267]: info: ------------------------------ 39ms Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS 음악FM","artist":"김정원의 아름다운 당신에게","album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8","service":"carrot_radio","albumart":"https://static.mytuner.mobi/media/tvos_radios/593/eumagfm-cbs-radio-music-fm.0f29a25a.png","disableVolumioPause":true} Mar 26 09:06:37 adv-volumio volumio[17267]: verbose: CURRENT POSITION 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus play Mar 26 09:06:37 adv-volumio volumio[17267]: info: Received an update from plugin. extracting info from payload Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:37 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:37 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:44 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioGetState Mar 26 09:06:44 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:47 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: dark_ascendants , handleBrowseUri Mar 26 09:06:47 adv-volumio volumio[17267]: info: [DarkAscendants] handleBrowseUri: darkascendants Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/70196863 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/269503788 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/171301080 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/171760168 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/152433440 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/269505340 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/284301056 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/127756614 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/171760185 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/71574293 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/80830319 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/3259836 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/112926270 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/80830195 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/2900538 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/80830279 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/4008992 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/3149933 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/112926271 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/545868 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/10923013 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/57611758 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/1456767 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/167889532 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/93709535 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/10922976 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/650231 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/217345 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/57611757 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preloading song: darkascendants/track/1325625 Mar 26 09:06:48 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:52 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::ClearQueue Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::stPlaybackTimer Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::serviceStop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::serviceStop Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::clearPlayQueue Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::addQueueItems Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::addQueueItems Mar 26 09:06:52 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:06:52 adv-volumio volumio[17267]: info: Adding Item to queue: darkascendants/playlist/016a50d6731b954265319098d7a4bb Mar 26 09:06:52 adv-volumio volumio[17267]: info: Exploding uri darkascendants/playlist/016a50d6731b954265319098d7a4bb in service dark_ascendants Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand stop took 46 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"stop","service":"carrot_radio","title":"","artist":"","album":"","albumart":"/albumart?sourceicon=music_service/carrot_radio/logos/carrot.png","uri":"","duration":0,"trackType":"webradio","disableVolumioPause":true} Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: CURRENT POSITION 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: No code Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: Mar 26 09:06:52 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:52 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:52 adv-volumio volumio[17267]: info: Mar 26 09:06:52 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:52 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:52 adv-volumio volumio[17267]: info: Mar 26 09:06:52 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:52 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand status took 8 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand status took 7 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand status took 6 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:52 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: 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","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: CURRENT POSITION 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: No code Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: 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","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: CURRENT POSITION 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: No code Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: 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","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8"} Mar 26 09:06:52 adv-volumio volumio[17267]: verbose: CURRENT POSITION 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: No code Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:52 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 7 Mar 26 09:06:52 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:53 adv-volumio volumio[17267]: info: ------------------------------ 102ms Mar 26 09:06:53 adv-volumio volumio[17267]: info: ------------------------------ 101ms Mar 26 09:06:53 adv-volumio volumio[17267]: info: ------------------------------ 100ms Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:06:53 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:06:53 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPlay Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::play index 0 Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::play index undefined Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:06:53 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:53 adv-volumio volumio[17267]: info: CoreStateMachine::startPlaybackTimer Mar 26 09:06:53 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:53 adv-volumio volumio[17267]: info: [DarkAscendants] clearAddPlayTrack quality=HI_RES_LOSSLESS useHiResProxy=true raw=true Mar 26 09:06:53 adv-volumio volumio[17267]: info: [DarkAscendants] clearAddPlayTrack: darkascendants/track/351642437 Mar 26 09:06:53 adv-volumio volumio[17267]: info: [DarkAscendants] Stopping tidal-connect service... Mar 26 09:06:53 adv-volumio sudo[18081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop tidal-connect Mar 26 09:06:53 adv-volumio sudo[18081]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:06:53 adv-volumio systemd[1]: Stopping Tidal Connect Service... Mar 26 09:06:53 adv-volumio systemd[1]: tidal-connect.service: Main process exited, code=killed, status=15/TERM Mar 26 09:06:53 adv-volumio systemd[1]: tidal-connect.service: Succeeded. Mar 26 09:06:53 adv-volumio systemd[1]: Stopped Tidal Connect Service. Mar 26 09:06:53 adv-volumio sudo[18081]: pam_unix(sudo:session): session closed for user root Mar 26 09:06:53 adv-volumio volumio[17267]: info: [DarkAscendants] tidal-connect stopped. Mar 26 09:06:54 adv-volumio volumio[17267]: info: [TidalApi] getStreamUrl[HI_RES_LOSSLESS] trackId=351642437 audioQuality=LOSSLESS mimeType=application/vnd.tidal.bts encType=NONE api.bitDepth=16 api.sampleRate=44100 Mar 26 09:06:54 adv-volumio volumio[17267]: info: [TidalApi] getStreamUrl OK(BTS): quality=LOSSLESS bitDepth=16 sampleRate=44100 bts.bitDepth=undefined bts.sampleRate=undefined Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:54 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/351642437","name":"YOU DON'T BRING ME FLOWERS","title":"YOU DON'T BRING ME FLOWERS","artist":"Salena Jones","album":"THOSE EYES","albumart":"https://resources.tidal.com/images/721f4175/a861/4fdf/9161/e2afd025a217/750x750.jpg","duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":0} Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: CURRENT POSITION 0 Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:06:54 adv-volumio volumio[17267]: info: sendMpdCommand stop took 1 milliseconds Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 09:06:54 adv-volumio volumio[17267]: info: Mar 26 09:06:54 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:54 adv-volumio volumio[17267]: info: sendMpdCommand clear took 2 milliseconds Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand add "https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxNWU5NmQ5NzI4NGI5ZGZjZTk4OWY0ZTkxOTk1MTVlYl82MS5tcDQ/0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==" Mar 26 09:06:54 adv-volumio volumio[17267]: info: Mar 26 09:06:54 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Mar 26 09:06:54 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:54 adv-volumio volumio[17267]: error: updateQueue error: null Mar 26 09:06:54 adv-volumio volumio[17267]: info: Mar 26 09:06:54 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:54 adv-volumio volumio[17267]: info: ------------------------------ 6ms Mar 26 09:06:54 adv-volumio volumio[17267]: info: sendMpdCommand add "https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxNWU5NmQ5NzI4NGI5ZGZjZTk4OWY0ZTkxOTk1MTVlYl82MS5tcDQ/0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==" took 5 milliseconds Mar 26 09:06:54 adv-volumio volumio[17267]: info: ------------------------------ 5ms Mar 26 09:06:54 adv-volumio volumio[17267]: info: ------------------------------ 4ms Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand play Mar 26 09:06:54 adv-volumio volumio[17267]: info: Mar 26 09:06:54 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Mar 26 09:06:54 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:06:54 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:06:54 adv-volumio volumio[17267]: info: ------------------------------ 7ms Mar 26 09:06:54 adv-volumio volumio[17267]: info: sendMpdCommand play took 5 milliseconds Mar 26 09:06:54 adv-volumio volumio[17267]: info: ------------------------------ 4ms Mar 26 09:06:54 adv-volumio volumio[17267]: info: ------------------------------ 3ms Mar 26 09:06:54 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:54 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/351642437","name":"YOU DON'T BRING ME FLOWERS","title":"YOU DON'T BRING ME FLOWERS","artist":"Salena Jones","album":"THOSE EYES","albumart":"https://resources.tidal.com/images/721f4175/a861/4fdf/9161/e2afd025a217/750x750.jpg","duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":0} Mar 26 09:06:54 adv-volumio volumio[17267]: verbose: CURRENT POSITION 0 Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus play Mar 26 09:06:54 adv-volumio volumio[17267]: info: Received an update from plugin. extracting info from payload Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:54 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:06:54 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:54 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: Mar 26 09:06:55 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:55 adv-volumio volumio[17267]: info: Mar 26 09:06:55 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:55 adv-volumio volumio[17267]: info: Mar 26 09:06:55 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:55 adv-volumio volumio[17267]: info: Mar 26 09:06:55 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand status took 6 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand status took 5 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand status took 3 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:55 adv-volumio volumio[17267]: info: Mar 26 09:06:55 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:55 adv-volumio volumio[17267]: info: Mar 26 09:06:55 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand status took 9 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand status took 6 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand status took 6 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:55 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:55 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:55 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 26 09:06:55 adv-volumio volumio[17267]: info: ------------------------------ 31ms Mar 26 09:06:55 adv-volumio volumio[17267]: info: ------------------------------ 29ms Mar 26 09:06:55 adv-volumio volumio[17267]: info: ------------------------------ 28ms Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:55 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:55 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:55 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 26 09:06:55 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:06:55 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:06:55 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 26 09:06:55 adv-volumio volumio[17267]: info: ------------------------------ 37ms Mar 26 09:06:55 adv-volumio volumio[17267]: info: ------------------------------ 31ms Mar 26 09:06:55 adv-volumio volumio[17267]: info: ------------------------------ 30ms Mar 26 09:07:04 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:04 adv-volumio volumio[17267]: info: sendMpdCommand status took 1 milliseconds Mar 26 09:07:14 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:14 adv-volumio volumio[17267]: info: sendMpdCommand status took 1 milliseconds Mar 26 09:07:24 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:24 adv-volumio volumio[17267]: info: sendMpdCommand status took 2 milliseconds Mar 26 09:07:27 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: carrot_radio , handleBrowseUri Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/155 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/156 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/158 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/159 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/3 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/7 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/10 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Preloading song: webcarrot/19 Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/155 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: Music Classic FM program=no Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/156 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: LINN Classic program=no Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/158 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/159 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: Naim Classical program=no Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/3 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: KBS ClassicFM program=yes Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/7 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: MBC FM4U program=yes Mar 26 09:07:27 adv-volumio volumio[17267]: info: Exploding uri webcarrot/10 in service carrot_radio Mar 26 09:07:27 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: SBS 파워FM program=yes Mar 26 09:07:28 adv-volumio volumio[17267]: info: Exploding uri webcarrot/19 in service carrot_radio Mar 26 09:07:28 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: CBS 음악FM program=yes Mar 26 09:07:28 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:07:28 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:28 adv-volumio volumio[17267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received carrot_radio Mar 26 09:07:28 adv-volumio volumio[17267]: info: [_resolveRecursive] Content-Type: audio/mpeg url: http://radio.linn.co.uk:8004/autodj Mar 26 09:07:28 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: http://radio.linn.co.uk:8004/autodj Mar 26 09:07:28 adv-volumio volumio[17267]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=kbs&ch=1fm Mar 26 09:07:28 adv-volumio volumio[17267]: info: [_resolveRecursive] Content-Type: audio/aac url: https://media-ssl.musicradio.com/ClassicFM Mar 26 09:07:28 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://media-ssl.musicradio.com/ClassicFM Mar 26 09:07:28 adv-volumio volumio[17267]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=sbs&ch=powerfm Mar 26 09:07:28 adv-volumio volumio[17267]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=mbc&ch=fm4u Mar 26 09:07:28 adv-volumio volumio[17267]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=cbs&ch=mfm Mar 26 09:07:29 adv-volumio volumio[17267]: info: [_resolveRecursive] Content-Type: audio/ogg url: https://mscp3.live-streams.nl:8252/class-flac.flac Mar 26 09:07:29 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://mscp3.live-streams.nl:8252/class-flac.flac Mar 26 09:07:29 adv-volumio volumio[17267]: info: [_resolveRecursive] 302 -> https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 26 09:07:29 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 Mar 26 09:07:29 adv-volumio volumio[17267]: info: [_resolveRecursive] 302 -> https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1MjY4NDksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjI2MzdmMTY0LTQyYTYtNDUyNS1iODUyLWY4MTZiYzM1MmEwZSIsImlhdCI6MTc3NDQ4MzY0OX0.rtZnrrdqWs9J57BvRMAAWFFEKfg-uM85QRF1cMveDbk Mar 26 09:07:29 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1MjY4NDksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjI2MzdmMTY0LTQyYTYtNDUyNS1iODUyLWY4MTZiYzM1MmEwZSIsImlhdCI6MTc3NDQ4MzY0OX0.rtZnrrdqWs9J57BvRMAAWFFEKfg-uM85QRF1cMveDbk Mar 26 09:07:29 adv-volumio volumio[17267]: info: [_resolveRecursive] 302 -> https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=67417417830F3C641B4BA56F3591F441159A3885E30C226F3C10F8aF864D3CB622aB33063B52A94350E2350124bAC118CBB07A5E6E24B7438C139D0FC1F3BA0BFA4574B8B85C04AA3E1980848C2F474FE22C431B8DA1E623E8F61CC0EC7D90185B30E9A79C730EA3AC2377D4D8EEBC43 Mar 26 09:07:29 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://minimw.imbc.com/dmfm/_definst_/mfm.stream/playlist.m3u8?_lsu_sa_=67417417830F3C641B4BA56F3591F441159A3885E30C226F3C10F8aF864D3CB622aB33063B52A94350E2350124bAC118CBB07A5E6E24B7438C139D0FC1F3BA0BFA4574B8B85C04AA3E1980848C2F474FE22C431B8DA1E623E8F61CC0EC7D90185B30E9A79C730EA3AC2377D4D8EEBC43 Mar 26 09:07:29 adv-volumio volumio[17267]: info: [_resolveRecursive] 302 -> https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTY0MjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=C9JRZlie5JsnE47S0OSRNODBE0B-lagkAsjFIaShpU9GCPAXzZRHZLEVx8bC6w2lXrpY95nVHqVuqeXRVfXSJRF5kfHaGnFrC6saooivaNqvABHNzW2mHJlqunMOAjUfewQ52lcGyskuil0x0q5U-uBeYDurhxQL3Dm1lUPMQ7unh-mTVlPYFJD1YL6C81CyN5ut7kRFeenI0ctecVigEC4jNnt9U1OjGoaRLO9G1tkWVSMS5xmTcPxyvm0bWhChk6obtYSq~eC-fcAXdLhbJE3eYfvrCspFiTk-~LbItcNWO4h9AlBDejxFHkRAfDhEa5zYhmbeUjD4d523jxkuvg__ Mar 26 09:07:29 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTY0MjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=C9JRZlie5JsnE47S0OSRNODBE0B-lagkAsjFIaShpU9GCPAXzZRHZLEVx8bC6w2lXrpY95nVHqVuqeXRVfXSJRF5kfHaGnFrC6saooivaNqvABHNzW2mHJlqunMOAjUfewQ52lcGyskuil0x0q5U-uBeYDurhxQL3Dm1lUPMQ7unh-mTVlPYFJD1YL6C81CyN5ut7kRFeenI0ctecVigEC4jNnt9U1OjGoaRLO9G1tkWVSMS5xmTcPxyvm0bWhChk6obtYSq~eC-fcAXdLhbJE3eYfvrCspFiTk-~LbItcNWO4h9AlBDejxFHkRAfDhEa5zYhmbeUjD4d523jxkuvg__ Mar 26 09:07:30 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::ClearQueue Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::stPlaybackTimer Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::serviceStop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::serviceStop Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::clearPlayQueue Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::addQueueItems Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::addQueueItems Mar 26 09:07:30 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:30 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/155 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/155 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/156 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/156 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/158 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Exploding uri webcarrot/158 in service carrot_radio Mar 26 09:07:30 adv-volumio volumio[17267]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no Mar 26 09:07:30 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/159 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/159 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/3 Mar 26 09:07:30 adv-volumio sudo[18179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start tidal-connect Mar 26 09:07:30 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/3 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/7 Mar 26 09:07:30 adv-volumio sudo[18179]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:07:30 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/7 Mar 26 09:07:30 adv-volumio volumio[17267]: info: Mar 26 09:07:30 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand stop took 46 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand status took 1 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: Mar 26 09:07:30 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:07:30 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:30 adv-volumio volumio[17267]: info: Mar 26 09:07:30 adv-volumio volumio[17267]: ---------------------------- MPD announces state update: player Mar 26 09:07:30 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand status took 6 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand status took 4 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: 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":"0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==","artist":null,"album":null,"uri":"https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxNWU5NmQ5NzI4NGI5ZGZjZTk4OWY0ZTkxOTk1MTVlYl82MS5tcDQ/0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==","trackType":"tidal"} Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: CURRENT POSITION 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: No code Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: ------------------------------ 58ms Mar 26 09:07:30 adv-volumio systemd[1]: Started Tidal Connect Service. Mar 26 09:07:30 adv-volumio sudo[18179]: pam_unix(sudo:session): session closed for user root Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","service":"carrot_radio","uri":"webcarrot/158","title":"Sveriges Radio P2","artist":"Global","album":"","albumart":"https://encrypted-tbn0.gstatic.com/images?q=tbn:ANd9GcRYIpztQbbI_wLzmkgOl5-yt8lesHsJUHXfbgqMTPM4lQ&s=10","duration":0,"trackType":"webradio","disableVolumioPause":true} Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: CURRENT POSITION 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 108 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 107 milliseconds Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: 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":"0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==","artist":null,"album":null,"uri":"https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxNWU5NmQ5NzI4NGI5ZGZjZTk4OWY0ZTkxOTk1MTVlYl82MS5tcDQ/0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==","trackType":"tidal"} Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: CURRENT POSITION 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus play Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::stPlaybackTimer Mar 26 09:07:30 adv-volumio volumio[17267]: info: ControllerMpd::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: 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":"0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==","artist":null,"album":null,"uri":"https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxNWU5NmQ5NzI4NGI5ZGZjZTk4OWY0ZTkxOTk1MTVlYl82MS5tcDQ/0.flac?token=1774487214~MGY4YzMyYjg3ZDFkMzI0MjdiZjU0ZjZkOGFiMTJhYWU1OGU1NDlmNg==","trackType":"tidal"} Mar 26 09:07:30 adv-volumio volumio[17267]: verbose: CURRENT POSITION 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: No code Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:30 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:30 adv-volumio volumio[17267]: info: ------------------------------ 188ms Mar 26 09:07:30 adv-volumio volumio[17267]: info: ------------------------------ 188ms Mar 26 09:07:30 adv-volumio volumio[17267]: info: [DarkAscendants] tidal-connect restarted. Mar 26 09:07:30 adv-volumio volumio[17267]: info: [_resolveRecursive] 302 -> https://edge1.sr.se/p2-flac Mar 26 09:07:31 adv-volumio start.sh[18181]: STARTING TidalConnect services, version: 1.1.3 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.front.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM front Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.rear Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.center_lfe Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.side Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround21 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround21 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround40.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround40 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround41 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround50 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround51 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround71.0:CARD=0' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround71 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM iec958 Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM spdif Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM spdif Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline Mar 26 09:07:31 adv-volumio start.sh[18181]: Cannot connect to server socket err = No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: Cannot connect to server request channel Mar 26 09:07:31 adv-volumio start.sh[18181]: jack server is not running or cannot be started Mar 26 09:07:31 adv-volumio start.sh[18181]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Mar 26 09:07:31 adv-volumio start.sh[18181]: Cannot connect to server socket err = No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: Cannot connect to server request channel Mar 26 09:07:31 adv-volumio start.sh[18181]: jack server is not running or cannot be started Mar 26 09:07:31 adv-volumio start.sh[18181]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm_oss.c:377:(_snd_pcm_oss_open) Unknown field port Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm_oss.c:377:(_snd_pcm_oss_open) Unknown field port Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm_a52.c:823:(_snd_pcm_a52_open) a52 is only for playback Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=6,AES1=130,AES2=0,AES3=2' Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM iec958:{AES0 0x6 AES1 0x82 AES2 0x0 AES3 0x2 CARD 0} Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm_usb_stream.c:486:(_snd_pcm_usb_stream_open) Invalid type for card Mar 26 09:07:31 adv-volumio start.sh[18181]: ALSA lib pcm_usb_stream.c:486:(_snd_pcm_usb_stream_open) Invalid type for card Mar 26 09:07:31 adv-volumio start.sh[18181]: Cannot connect to server socket err = No such file or directory Mar 26 09:07:31 adv-volumio start.sh[18181]: Cannot connect to server request channel Mar 26 09:07:31 adv-volumio start.sh[18181]: jack server is not running or cannot be started Mar 26 09:07:31 adv-volumio start.sh[18181]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Mar 26 09:07:31 adv-volumio start.sh[18181]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Mar 26 09:07:31 adv-volumio start.sh[18181]: STARTED TidalConnect services. Mar 26 09:07:31 adv-volumio volumio[17267]: info: [_resolveRecursive] Content-Type: application/ogg url: https://edge1.sr.se/p2-flac Mar 26 09:07:32 adv-volumio volumio[17267]: info: [_parsePlaylistBody] unrecognized content, using: https://edge1.sr.se/p2-flac Mar 26 09:07:32 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://edge1.sr.se/p2-flac Mar 26 09:07:39 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:39 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 09:07:39 adv-volumio volumio[17267]: info: CoreStateMachine::ClearQueue Mar 26 09:07:39 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:07:39 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:39 adv-volumio volumio[17267]: info: CorePlayQueue::clearPlayQueue Mar 26 09:07:39 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:07:39 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:39 adv-volumio volumio[17267]: info: CoreStateMachine::addQueueItems Mar 26 09:07:39 adv-volumio volumio[17267]: info: CorePlayQueue::addQueueItems Mar 26 09:07:39 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/155 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/155 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/156 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/156 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/158 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/158 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/159 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/159 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/3 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/3 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/7 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/7 Mar 26 09:07:39 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/10 Mar 26 09:07:40 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/10 Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPlay Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::play index 6 Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::addQueueItems Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::addQueueItems Mar 26 09:07:40 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:40 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/19 Mar 26 09:07:40 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/19 Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::play index undefined Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::startPlaybackTimer Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:07:40 adv-volumio volumio[17267]: info: sendMpdCommand stop took 5 milliseconds Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 09:07:40 adv-volumio volumio[17267]: info: Mar 26 09:07:40 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:07:40 adv-volumio volumio[17267]: info: sendMpdCommand clear took 2 milliseconds Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand add "https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1MjY4NDksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjI2MzdmMTY0LTQyYTYtNDUyNS1iODUyLWY4MTZiYzM1MmEwZSIsImlhdCI6MTc3NDQ4MzY0OX0.rtZnrrdqWs9J57BvRMAAWFFEKfg-uM85QRF1cMveDbk" Mar 26 09:07:40 adv-volumio volumio[17267]: info: Mar 26 09:07:40 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Mar 26 09:07:40 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:07:40 adv-volumio volumio[17267]: error: updateQueue error: null Mar 26 09:07:40 adv-volumio volumio[17267]: info: Mar 26 09:07:40 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:07:40 adv-volumio volumio[17267]: info: ------------------------------ 10ms Mar 26 09:07:40 adv-volumio volumio[17267]: info: sendMpdCommand add "https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1MjY4NDksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjI2MzdmMTY0LTQyYTYtNDUyNS1iODUyLWY4MTZiYzM1MmEwZSIsImlhdCI6MTc3NDQ4MzY0OX0.rtZnrrdqWs9J57BvRMAAWFFEKfg-uM85QRF1cMveDbk" took 8 milliseconds Mar 26 09:07:40 adv-volumio volumio[17267]: info: ------------------------------ 9ms Mar 26 09:07:40 adv-volumio volumio[17267]: info: ------------------------------ 7ms Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand play Mar 26 09:07:40 adv-volumio volumio[17267]: info: Mar 26 09:07:40 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Mar 26 09:07:40 adv-volumio volumio[17267]: ---------------------------- MPD announces system playlist update Mar 26 09:07:40 adv-volumio volumio[17267]: info: Ignoring MPD Status Update Mar 26 09:07:40 adv-volumio volumio[17267]: info: ------------------------------ 13ms Mar 26 09:07:40 adv-volumio volumio[17267]: info: sendMpdCommand play took 5 milliseconds Mar 26 09:07:40 adv-volumio volumio[17267]: info: ------------------------------ 5ms Mar 26 09:07:40 adv-volumio volumio[17267]: info: ------------------------------ 4ms Mar 26 09:07:40 adv-volumio volumio[17267]: info: [clearAddPlayTrack] title=SBS 파워FM program=yes Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:40 adv-volumio volumio[17267]: info: [startProgramRefresh] SBS 파워FM Mar 26 09:07:40 adv-volumio volumio[17267]: info: Pushing Favourites {"uri":"webcarrot/10","favourite":false} Mar 26 09:07:40 adv-volumio volumio[17267]: info: [fetchProgramInfo] https://radio.yuntae.in/api/onair/program?stn=sbs&ch=power -> 아름다운 이 아침, 봉태규입니다 Mar 26 09:07:40 adv-volumio volumio[17267]: info: [programRefresh] fetched: 아름다운 이 아침, 봉태규입니다 Mar 26 09:07:40 adv-volumio volumio[17267]: info: ControllerMpd::getState Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand status Mar 26 09:07:40 adv-volumio volumio[17267]: info: sendMpdCommand status took 1 milliseconds Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::parseState Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 09:07:40 adv-volumio volumio[17267]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: ControllerMpd::parseTrackInfo Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:40 adv-volumio volumio[17267]: info: [programRefresh] queue updated Mar 26 09:07:40 adv-volumio volumio[17267]: info: [programRefresh] pushed: status=play program=아름다운 이 아침, 봉태규입니다 Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreCommandRouter::servicePushState Mar 26 09:07:40 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SBS 파워FM","artist":"아름다운 이 아침, 봉태규입니다","album":null,"uri":"https://radiolive.sbs.co.kr/powerpc/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NzQ1MjY4NDksInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjI2MzdmMTY0LTQyYTYtNDUyNS1iODUyLWY4MTZiYzM1MmEwZSIsImlhdCI6MTc3NDQ4MzY0OX0.rtZnrrdqWs9J57BvRMAAWFFEKfg-uM85QRF1cMveDbk","trackType":"","service":"carrot_radio","albumart":"https://program-image.cloud.sbs.co.kr/power.jpg","disableVolumioPause":true} Mar 26 09:07:40 adv-volumio volumio[17267]: verbose: CURRENT POSITION 6 Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::syncState stateService play Mar 26 09:07:40 adv-volumio volumio[17267]: info: CoreStateMachine::syncState currentStatus stop Mar 26 09:07:41 adv-volumio volumio[17267]: error: [resolveStreamUrl] failed: Error: timeout, fallback to: https://live1.sr.se/p2-flac Mar 26 09:07:41 adv-volumio volumio[17267]: info: [ControllerCarrotRadio::explodeUri] resolved: https://live1.sr.se/p2-flac Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPlay Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::play index 5 Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::addQueueItems Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::addQueueItems Mar 26 09:07:41 adv-volumio volumio[17267]: info: Preload queue cleared Mar 26 09:07:41 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/10 Mar 26 09:07:41 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/10 Mar 26 09:07:41 adv-volumio volumio[17267]: info: Adding Item to queue: webcarrot/19 Mar 26 09:07:41 adv-volumio volumio[17267]: info: Using cached record of: webcarrot/19 Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::stop Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::stPlaybackTimer Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::pushState Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::serviceStop Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::getTrack 6 Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreCommandRouter::serviceStop Mar 26 09:07:41 adv-volumio volumio[17267]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::saveQueue Mar 26 09:07:41 adv-volumio volumio[17267]: info: CoreStateMachine::updateTrackBlock Mar 26 09:07:41 adv-volumio volumio[17267]: info: CorePlayQueue::getTrackBlock Mar 26 09:07:41 adv-volumio volumio[17267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 09:07:41 adv-volumio volumio[17267]: Error: Unable to resolve or reject the same promise twice Mar 26 09:07:41 adv-volumio volumio[17267]: at Promise.reject (/data/plugins/music_service/carrot_radio/node_modules/kew/kew.js:189:43) Mar 26 09:07:41 adv-volumio volumio[17267]: at ClientRequest. (/data/plugins/music_service/carrot_radio/index.js:754:39) Mar 26 09:07:41 adv-volumio volumio[17267]: at ClientRequest.emit (events.js:315:20) Mar 26 09:07:41 adv-volumio volumio[17267]: at TLSSocket.socketCloseListener (_http_client.js:443:11) Mar 26 09:07:41 adv-volumio volumio[17267]: at TLSSocket.emit (events.js:327:22) Mar 26 09:07:41 adv-volumio volumio[17267]: at net.js:673:12 Mar 26 09:07:41 adv-volumio volumio[17267]: at TCP.done (_tls_wrap.js:563:7) Mar 26 09:07:41 adv-volumio volumio[17267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 09:07:42 adv-volumio sudo[18228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-26 09:06 Mar 26 09:07:42 adv-volumio sudo[18228]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"