-- Logs begin at Wed 2026-03-25 17:00:51 KST, end at Thu 2026-03-26 09:07:57 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) Mar 26 09:07:42 adv-volumio sudo[18228]: pam_unix(sudo:session): session closed for user root Mar 26 09:07:43 adv-volumio volumio-remote-updater[4285]: [2026-03-26 09:07:43] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 26 09:07:43 adv-volumio volumio-remote-updater[4285]: [2026-03-26 09:07:43] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 26 09:07:43 adv-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 26 09:07:43 adv-volumio volumio5-onboarding[1342]: time=2026-03-26T09:07:43.792+09:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 26 09:07:43 adv-volumio volumio5-onboarding[1342]: time=2026-03-26T09:07:43.800+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 26 09:07:43 adv-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 26 09:07:43 adv-volumio systemd[1]: Started dynamicswap service. Mar 26 09:07:43 adv-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 26 09:07:44 adv-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 26 09:07:44 adv-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 26 09:07:44 adv-volumio systemd[1]: Started dynamicswap service. Mar 26 09:07:44 adv-volumio systemd[1]: Stopped Volumio Backend Module. Mar 26 09:07:44 adv-volumio systemd[1]: Started Volumio Backend Module. Mar 26 09:07:44 adv-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 26 09:07:44 adv-volumio volumio5-onboarding[1342]: time=2026-03-26T09:07:44.802+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 26 09:07:45 adv-volumio volumio[18255]: info: ------------------------------------------- Mar 26 09:07:45 adv-volumio volumio[18255]: info: ----- Volumio3 ---- Mar 26 09:07:45 adv-volumio volumio[18255]: info: ------------------------------------------- Mar 26 09:07:45 adv-volumio volumio[18255]: info: ----- System startup ---- Mar 26 09:07:45 adv-volumio volumio[18255]: info: ------------------------------------------- Mar 26 09:07:46 adv-volumio volumio[18255]: info: MYVOLUMIO Environment detected Mar 26 09:07:46 adv-volumio volumio[18255]: info: Plugin folders cleanup Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning into folder /volumio/app/plugins/ Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category audio_interface Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category miscellanea Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category music_service Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category plugins.json Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category system_controller Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category user_interface Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning into folder /data/plugins/ Mar 26 09:07:46 adv-volumio volumio[18255]: info: Scanning category music_service Mar 26 09:07:46 adv-volumio volumio[18255]: info: Plugin folders cleanup completed Mar 26 09:07:46 adv-volumio volumio[18255]: info: ------------------------------------------- Mar 26 09:07:46 adv-volumio volumio[18255]: info: ----- Core plugins startup ---- Mar 26 09:07:46 adv-volumio volumio[18255]: info: ------------------------------------------- Mar 26 09:07:46 adv-volumio volumio[18255]: info: Loading plugins from folder /volumio/app/plugins/ Mar 26 09:07:46 adv-volumio volumio[18255]: info: Adding plugin upnp to MyMusic Plugins Mar 26 09:07:46 adv-volumio volumio[18255]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 26 09:07:46 adv-volumio volumio[18255]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 26 09:07:46 adv-volumio volumio[18255]: info: Loading plugins from folder /data/plugins/ Mar 26 09:07:46 adv-volumio volumio[18255]: info: Loading plugin "system"... Mar 26 09:07:46 adv-volumio volumio[18255]: info: Loading plugin "appearance"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "network"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: Refreshing Cached IP Addresses Mar 26 09:07:47 adv-volumio sudo[18282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 09:07:47 adv-volumio sudo[18282]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:07:47 adv-volumio sudo[18282]: pam_unix(sudo:session): session closed for user root Mar 26 09:07:47 adv-volumio sudo[18284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 09:07:47 adv-volumio sudo[18284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:07:47 adv-volumio sudo[18284]: pam_unix(sudo:session): session closed for user root Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "services"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "volumio5onboarding"... Mar 26 09:07:47 adv-volumio sudo[18293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 26 09:07:47 adv-volumio sudo[18293]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "alsa_controller"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "wizard"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "networkfs"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: Starting Udev Watcher for removable devices Mar 26 09:07:47 adv-volumio sudo[18308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=carrot,password=Ilo2dul222,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.68/Music /mnt/NAS/WZYDisk Mar 26 09:07:47 adv-volumio sudo[18308]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:07:47 adv-volumio volumio[18255]: info: Ignoring mount for partition: boot Mar 26 09:07:47 adv-volumio volumio[18255]: info: Ignoring mount for partition: volumio Mar 26 09:07:47 adv-volumio volumio[18255]: info: Ignoring mount for partition: volumio_data Mar 26 09:07:47 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "volumio_command_line_client"... Mar 26 09:07:47 adv-volumio kernel: CIFS: Attempting to mount //192.168.10.68/Music Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "upnp"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: [1774483667727] Starting Upmpd Daemon Mar 26 09:07:47 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "my_music"... Mar 26 09:07:47 adv-volumio volumio[18255]: info: Loading plugin "mpd"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "upnp_browser"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Starting UPNP Browser Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "alarm-clock"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "airplay_emulation"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Starting Shairport Sync Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "last_100"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "webradio"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "i2s_dacs"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: I2S DAC not set, start Auto-detection Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "volumiodiscovery"... Mar 26 09:07:48 adv-volumio volumio[18255]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 09:07:48 adv-volumio volumio[18255]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 09:07:48 adv-volumio volumio[18255]: *** WARNING *** For more information see Mar 26 09:07:48 adv-volumio volumio[18255]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 09:07:48 adv-volumio node[18255]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 09:07:48 adv-volumio volumio[18255]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 09:07:48 adv-volumio volumio[18255]: *** WARNING *** For more information see Mar 26 09:07:48 adv-volumio node[18255]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 09:07:48 adv-volumio node[18255]: *** WARNING *** For more information see Mar 26 09:07:48 adv-volumio node[18255]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 09:07:48 adv-volumio node[18255]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 09:07:48 adv-volumio node[18255]: *** WARNING *** For more information see Mar 26 09:07:48 adv-volumio volumio[18255]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 26 09:07:48 adv-volumio volumio[18255]: info: Discovery: Started advertising with name: ADV-Volumio Mar 26 09:07:48 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "outputs"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "albumart"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Plugin example_plugin is not enabled Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "inputs"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "updater_comm"... Mar 26 09:07:48 adv-volumio volumio-remote-updater[4285]: [2026-03-26 09:07:48] [connect] Successful connection Mar 26 09:07:48 adv-volumio volumio[18255]: info: Plugin mpdemulation is not enabled Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "rest_api"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "websocket"... Mar 26 09:07:48 adv-volumio volumio[18255]: info: Starting Socket.io Server version 2.3.0 Mar 26 09:07:48 adv-volumio volumio[18255]: info: Loading plugin "carrot_radio"... Mar 26 09:07:49 adv-volumio volumio[18255]: Forking 3 albumart workers Mar 26 09:07:49 adv-volumio volumio[18255]: info: ControllerCarrotRadio::constructor Mar 26 09:07:49 adv-volumio volumio[18255]: info: Loading plugin "dark_ascendants"... Mar 26 09:07:49 adv-volumio volumio[18255]: info: Plugin personal_radio is not enabled Mar 26 09:07:49 adv-volumio volumio[18255]: info: Loading i18n strings for locale ko Mar 26 09:07:49 adv-volumio volumio[18255]: Updating browse sources language Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::initPlayerControls Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:49 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 09:07:50 adv-volumio volumio[18255]: Express server listening on port 3000 Mar 26 09:07:50 adv-volumio volumio[18255]: [Metrics] WebUI: 4s 741.89ms Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::resetVolumioState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::getcurrentVolume Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::pushState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 09:07:50 adv-volumio volumio[18255]: Starting albumart workers Mar 26 09:07:50 adv-volumio volumio[18255]: info: Volumio Network Manager: Network status updated: 3 Mar 26 09:07:50 adv-volumio volumio[18255]: Starting albumart workers Mar 26 09:07:50 adv-volumio volumio[18255]: Starting albumart workers Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.132 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.131 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.132 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.131 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.132 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.131 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.132 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.131 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 26 09:07:50 adv-volumio volumio-remote-updater[4285]: [2026-03-26 09:07:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1774483668 101 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.132 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.131 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to 192.168.11.132 from 192.168.11.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Whale/4.36.368.10 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 26 09:07:50 adv-volumio volumio[18255]: info: Reloading queue from file Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::setRepeat false single undefined Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::pushState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::setRandom null Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::pushState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioPushState Mar 26 09:07:50 adv-volumio volumio[18255]: info: Setting Device type: Raspberry PI Mar 26 09:07:50 adv-volumio volumio[18255]: info: Received Get System Info Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 09:07:50 adv-volumio volumio[18255]: info: Discovery: Getting this device information Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetQueue Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreStateMachine::getQueue Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getQueue Mar 26 09:07:50 adv-volumio volumio[18255]: info: Listing playlists Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 09:07:50 adv-volumio volumio[18255]: info: Received Get System Info Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 09:07:50 adv-volumio volumio[18255]: info: Discovery: Getting this device information Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 09:07:50 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:50 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:50 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: info: Discovery: adding 849387c8-8797-4920-be2a-7a9ec82fccd0 Mar 26 09:07:51 adv-volumio volumio[18255]: info: Discovery: Found device ADV-Volumio Mar 26 09:07:51 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:51 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:51 adv-volumio volumio[18255]: info: Discovery: this is already registered, 849387c8-8797-4920-be2a-7a9ec82fccd0 Mar 26 09:07:51 adv-volumio volumio[18255]: info: Discovery: Found device ADV-Volumio Mar 26 09:07:51 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:51 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio sudo[18293]: pam_unix(sudo:session): session closed for user root Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:51 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Mar 26 09:07:52 adv-volumio volumio[18255]: info: Received Get System Info Mar 26 09:07:52 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 09:07:52 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 09:07:52 adv-volumio volumio[18255]: info: Discovery: Getting this device information Mar 26 09:07:52 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioGetState Mar 26 09:07:52 adv-volumio volumio[18255]: info: CorePlayQueue::getTrack 0 Mar 26 09:07:52 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 09:07:52 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 09:07:52 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 09:07:53 adv-volumio volumio5-onboarding[1342]: time=2026-03-26T09:07:53.112+09:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 26 09:07:55 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 09:07:55 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 09:07:56 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 09:07:56 adv-volumio volumio[18255]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 09:07:57 adv-volumio volumio[18255]: info: Preload queue cleared Mar 26 09:07:57 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 09:07:57 adv-volumio volumio[18255]: info: CoreStateMachine::ClearQueue Mar 26 09:07:57 adv-volumio volumio[18255]: info: CoreStateMachine::stop Mar 26 09:07:57 adv-volumio volumio[18255]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 09:07:57 adv-volumio volumio[18255]: info: CorePlayQueue::clearPlayQueue Mar 26 09:07:57 adv-volumio volumio[18255]: info: CorePlayQueue::saveQueue Mar 26 09:07:57 adv-volumio volumio[18255]: info: CoreCommandRouter::volumioPushQueue Mar 26 09:07:57 adv-volumio volumio[18255]: info: CoreStateMachine::addQueueItems Mar 26 09:07:57 adv-volumio volumio[18255]: info: CorePlayQueue::addQueueItems Mar 26 09:07:57 adv-volumio volumio[18255]: info: Preload queue cleared Mar 26 09:07:57 adv-volumio volumio[18255]: info: Adding Item to queue: webcarrot/155 Mar 26 09:07:57 adv-volumio volumio[18255]: info: Exploding uri webcarrot/155 in service carrot_radio Mar 26 09:07:57 adv-volumio volumio[18255]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 09:07:57 adv-volumio volumio[18255]: TypeError: Cannot read property 'find' of undefined Mar 26 09:07:57 adv-volumio volumio[18255]: at ControllerCarrotRadio.explodeUri (/data/plugins/music_service/carrot_radio/index.js:445:31) Mar 26 09:07:57 adv-volumio volumio[18255]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Mar 26 09:07:57 adv-volumio volumio[18255]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 26 09:07:57 adv-volumio volumio[18255]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 26 09:07:57 adv-volumio volumio[18255]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 26 09:07:57 adv-volumio volumio[18255]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Mar 26 09:07:57 adv-volumio volumio[18255]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Mar 26 09:07:57 adv-volumio volumio[18255]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Mar 26 09:07:57 adv-volumio volumio[18255]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Mar 26 09:07:57 adv-volumio volumio[18255]: at Socket.emit (events.js:315:20) Mar 26 09:07:57 adv-volumio volumio[18255]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 26 09:07:57 adv-volumio volumio[18255]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 26 09:07:57 adv-volumio volumio[18255]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 09:07:57 adv-volumio sudo[18399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-26 09:06 Mar 26 09:07:57 adv-volumio sudo[18399]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 09:07:57 adv-volumio sudo[18308]: pam_unix(sudo:session): session closed for user root Mar 26 09:07:57 adv-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 26 09:07:57 adv-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 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"