-- Logs begin at Sat 2025-04-12 12:51:59 UTC, end at Sat 2025-04-12 13:18:38 UTC. -- Apr 12 13:17:02 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 13:17:10 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio Apr 12 13:17:10 volumio volumio[793]: info: {"name":"KBS부산1라디오"} Apr 12 13:17:10 volumio volumio[793]: info: Saving local item my-web-radio Apr 12 13:17:10 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:12 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:12 volumio volumio[793]: info: [1744463832486] ControllerWebradio::stop Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:12 volumio volumio[793]: info: Adding Item to queue: https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_720.smil/playlist.m3u8 Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:12 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:12 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:12 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:12 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:12 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:12 volumio volumio[793]: info: [1744463832508] ControllerWebradio::clearAddPlayTrack Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:12 volumio volumio[793]: info: sendMpdCommand stop took 45 milliseconds Apr 12 13:17:12 volumio volumio[793]: info: Apr 12 13:17:12 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:12 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:12 volumio volumio[793]: info: sendMpdCommand stop took 26 milliseconds Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:12 volumio volumio[793]: info: Apr 12 13:17:12 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:12 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:12 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:17:12 volumio volumio[793]: info: sendMpdCommand clear took 2 milliseconds Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand load "https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_720.smil/playlist.m3u8" Apr 12 13:17:12 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:12 volumio volumio[793]: info: ------------------------------ 4ms Apr 12 13:17:12 volumio volumio[793]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 13:17:12 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:12 volumio volumio[793]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 13:17:12 volumio volumio[793]: info: ------------------------------ 9ms Apr 12 13:17:13 volumio volumio[793]: info: sendMpdCommand load "https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_720.smil/playlist.m3u8" took 476 milliseconds Apr 12 13:17:13 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 13:17:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:13 volumio volumio[793]: info: Apr 12 13:17:13 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:13 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:13 volumio volumio[793]: info: sendMpdCommand play took 2 milliseconds Apr 12 13:17:13 volumio volumio[793]: info: ------------------------------ 1ms Apr 12 13:17:14 volumio volumio[793]: info: Apr 12 13:17:14 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:14 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:14 volumio volumio[793]: info: Apr 12 13:17:14 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:14 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:14 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:14 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:17:14 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:14 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:14 volumio volumio[793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":212,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"chunklist_b1128000.m3u8","artist":null,"album":null,"uri":"https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_720.smil/chunklist_b1128000.m3u8","trackType":"m3u8"} Apr 12 13:17:14 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:14 volumio volumio[793]: info: ------------------------------ 22ms Apr 12 13:17:14 volumio volumio[793]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 12 13:17:14 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:14 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:14 volumio volumio[793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"chunklist_b1128000.m3u8","artist":null,"album":null,"uri":"https://cdnfm.tbs.seoul.kr/tbs/_definst_/tbs_fm_web_720.smil/chunklist_b1128000.m3u8","trackType":"m3u8"} Apr 12 13:17:14 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:17:14 volumio volumio[793]: info: Received an update from plugin. extracting info from payload Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:14 volumio volumio[793]: info: ------------------------------ 41ms Apr 12 13:17:23 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio Apr 12 13:17:23 volumio volumio[793]: info: {"name":"TBS 교통방송(서울)"} Apr 12 13:17:23 volumio volumio[793]: info: Saving local item my-web-radio Apr 12 13:17:23 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 13:17:39 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 12 13:17:41 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:43 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:43 volumio volumio[793]: info: [1744463863255] ControllerWebradio::stop Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:43 volumio volumio[793]: info: Adding Item to queue: websbs/0 Apr 12 13:17:43 volumio volumio[793]: info: Exploding uri websbs/0 in service personal_radio Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:43 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:43 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand stop took 90 milliseconds Apr 12 13:17:43 volumio volumio[793]: info: Apr 12 13:17:43 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:43 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand stop took 24 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:43 volumio volumio[793]: info: Apr 12 13:17:43 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:43 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand clear took 2 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjMsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.xOae5t6gvtM_gSRzBl6Zrf32LhNlPMKewLqXJBat9-o" Apr 12 13:17:43 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:43 volumio volumio[793]: info: Apr 12 13:17:43 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:43 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:43 volumio volumio[793]: info: ------------------------------ 5ms Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand add "rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjMsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.xOae5t6gvtM_gSRzBl6Zrf32LhNlPMKewLqXJBat9-o" took 3 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:43 volumio volumio[793]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 13:17:43 volumio volumio[793]: info: ------------------------------ 12ms Apr 12 13:17:43 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand play took 3 milliseconds Apr 12 13:17:43 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:43 volumio volumio[793]: 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":"lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjMsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.xOae5t6gvtM_gSRzBl6Zrf32LhNlPMKewLqXJBat9-o","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjMsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.xOae5t6gvtM_gSRzBl6Zrf32LhNlPMKewLqXJBat9-o","trackType":""} Apr 12 13:17:43 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:43 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:43 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:43 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:43 volumio nmbd[844]: [2025/04/12 13:17:43.467636, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 12 13:17:43 volumio nmbd[844]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 12 13:17:43 volumio volumio[793]: info: Apr 12 13:17:43 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:43 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand status took 7 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand clearerror took 3 milliseconds Apr 12 13:17:43 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:17:43 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:44 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:44 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:44 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:44 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:44 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:44 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:44 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:44 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:44 volumio volumio[793]: info: ------------------------------ 23ms Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:45 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:45 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:45 volumio volumio[793]: info: Adding Item to queue: websbs/1 Apr 12 13:17:45 volumio volumio[793]: info: Exploding uri websbs/1 in service personal_radio Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand stop took 27 milliseconds Apr 12 13:17:45 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: 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":"lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjMsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.xOae5t6gvtM_gSRzBl6Zrf32LhNlPMKewLqXJBat9-o","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjMsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.xOae5t6gvtM_gSRzBl6Zrf32LhNlPMKewLqXJBat9-o","trackType":""} Apr 12 13:17:45 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: No code Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:45 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:45 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand stop took 7 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:45 volumio volumio[793]: info: Apr 12 13:17:45 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:45 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand clear took 1 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjUsInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.qioDIDYtaEiZUz8Me5ZG0cUP122fF4Ox2k9GQkYhcUo" Apr 12 13:17:45 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:45 volumio volumio[793]: info: Apr 12 13:17:45 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:45 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:45 volumio volumio[793]: info: ------------------------------ 3ms Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand add "rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjUsInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.qioDIDYtaEiZUz8Me5ZG0cUP122fF4Ox2k9GQkYhcUo" took 2 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:45 volumio volumio[793]: info: ------------------------------ 5ms Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand play took 2 milliseconds Apr 12 13:17:45 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: 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":"powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjUsInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.qioDIDYtaEiZUz8Me5ZG0cUP122fF4Ox2k9GQkYhcUo","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjUsInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.qioDIDYtaEiZUz8Me5ZG0cUP122fF4Ox2k9GQkYhcUo","trackType":""} Apr 12 13:17:45 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: Apr 12 13:17:45 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:45 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand clearerror took 3 milliseconds Apr 12 13:17:45 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:45 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:45 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:45 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:45 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:45 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:45 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:45 volumio volumio[793]: info: ------------------------------ 27ms Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:46 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:46 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:46 volumio volumio[793]: info: Adding Item to queue: websbs/2 Apr 12 13:17:46 volumio volumio[793]: info: Exploding uri websbs/2 in service personal_radio Apr 12 13:17:46 volumio volumio[793]: info: sendMpdCommand stop took 32 milliseconds Apr 12 13:17:46 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:46 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:46 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: 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":"powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjUsInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.qioDIDYtaEiZUz8Me5ZG0cUP122fF4Ox2k9GQkYhcUo","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjUsInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.qioDIDYtaEiZUz8Me5ZG0cUP122fF4Ox2k9GQkYhcUo","trackType":""} Apr 12 13:17:46 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:46 volumio volumio[793]: info: No code Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:46 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:46 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:46 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:46 volumio volumio[793]: info: sendMpdCommand stop took 6 milliseconds Apr 12 13:17:46 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:46 volumio volumio[793]: info: Apr 12 13:17:47 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:47 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand clear took 2 milliseconds Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "rtmp://live.sbs.co.kr/sbsdmbpc/_definst_/sbsdmb.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjYsInBhdGgiOiIvc2JzZG1icGMvX2RlZmluc3RfL3Nic2RtYi5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.7QcZgLEPklmks2xFvh5LlZXRkfNeo_uhsmA7GlD2gHk" Apr 12 13:17:47 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:47 volumio volumio[793]: info: Apr 12 13:17:47 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:47 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:47 volumio volumio[793]: info: ------------------------------ 7ms Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand add "rtmp://live.sbs.co.kr/sbsdmbpc/_definst_/sbsdmb.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjYsInBhdGgiOiIvc2JzZG1icGMvX2RlZmluc3RfL3Nic2RtYi5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.7QcZgLEPklmks2xFvh5LlZXRkfNeo_uhsmA7GlD2gHk" took 6 milliseconds Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:47 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand play took 2 milliseconds Apr 12 13:17:47 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:47 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:47 volumio volumio[793]: 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":"sbsdmb.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjYsInBhdGgiOiIvc2JzZG1icGMvX2RlZmluc3RfL3Nic2RtYi5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.7QcZgLEPklmks2xFvh5LlZXRkfNeo_uhsmA7GlD2gHk","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/sbsdmbpc/_definst_/sbsdmb.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjYsInBhdGgiOiIvc2JzZG1icGMvX2RlZmluc3RfL3Nic2RtYi5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.7QcZgLEPklmks2xFvh5LlZXRkfNeo_uhsmA7GlD2gHk","trackType":""} Apr 12 13:17:47 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:47 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:47 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:47 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:47 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:47 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:47 volumio volumio[793]: info: Apr 12 13:17:47 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:47 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand clearerror took 2 milliseconds Apr 12 13:17:47 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:47 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:47 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:47 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:47 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:47 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:47 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:47 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:47 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:47 volumio volumio[793]: info: ------------------------------ 13ms Apr 12 13:17:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:51 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:51 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:51 volumio volumio[793]: info: Adding Item to queue: webmbc/0 Apr 12 13:17:51 volumio volumio[793]: info: Exploding uri webmbc/0 in service personal_radio Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand stop took 34 milliseconds Apr 12 13:17:51 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: 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":"sbsdmb.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjYsInBhdGgiOiIvc2JzZG1icGMvX2RlZmluc3RfL3Nic2RtYi5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.7QcZgLEPklmks2xFvh5LlZXRkfNeo_uhsmA7GlD2gHk","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/sbsdmbpc/_definst_/sbsdmb.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNjYsInBhdGgiOiIvc2JzZG1icGMvX2RlZmluc3RfL3Nic2RtYi5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.7QcZgLEPklmks2xFvh5LlZXRkfNeo_uhsmA7GlD2gHk","trackType":""} Apr 12 13:17:51 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:51 volumio volumio[793]: info: No code Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:51 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:51 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand stop took 8 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:51 volumio volumio[793]: info: Apr 12 13:17:51 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:51 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand clear took 3 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" Apr 12 13:17:51 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:51 volumio volumio[793]: info: Apr 12 13:17:51 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:51 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:51 volumio volumio[793]: info: ------------------------------ 5ms Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" took 3 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:51 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand play took 4 milliseconds Apr 12 13:17:51 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:51 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:51 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Apr 12 13:17:51 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:51 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:51 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:52 volumio volumio[793]: info: Apr 12 13:17:52 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:52 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:52 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:52 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:52 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Apr 12 13:17:52 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:52 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:52 volumio volumio[793]: info: sendMpdCommand clearerror took 2 milliseconds Apr 12 13:17:52 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:17:52 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:52 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:52 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:52 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:52 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:52 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:52 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:52 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:52 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:52 volumio volumio[793]: info: ------------------------------ 16ms Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:53 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:53 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:53 volumio volumio[793]: info: Adding Item to queue: webmbc/1 Apr 12 13:17:53 volumio volumio[793]: info: Exploding uri webmbc/1 in service personal_radio Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand stop took 31 milliseconds Apr 12 13:17:53 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: 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":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Apr 12 13:17:53 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: No code Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:53 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:53 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand stop took 6 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:53 volumio volumio[793]: info: Apr 12 13:17:53 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:53 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand clear took 1 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8" Apr 12 13:17:53 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:53 volumio volumio[793]: info: Apr 12 13:17:53 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:53 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:53 volumio volumio[793]: info: ------------------------------ 2ms Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand add "http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8" took 2 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:53 volumio volumio[793]: info: ------------------------------ 4ms Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand play took 4 milliseconds Apr 12 13:17:53 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8","trackType":"m3u8"} Apr 12 13:17:53 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: Apr 12 13:17:53 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:53 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand clearerror took 2 milliseconds Apr 12 13:17:53 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:17:53 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:53 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:53 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:53 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:53 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:53 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:53 volumio volumio[793]: info: ------------------------------ 18ms Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:54 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:54 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:54 volumio volumio[793]: info: Adding Item to queue: webmbc/2 Apr 12 13:17:54 volumio volumio[793]: info: Exploding uri webmbc/2 in service personal_radio Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand stop took 56 milliseconds Apr 12 13:17:54 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: 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":"http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8","trackType":"m3u8"} Apr 12 13:17:54 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:54 volumio volumio[793]: info: No code Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:54 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:54 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand stop took 6 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:54 volumio volumio[793]: info: Apr 12 13:17:54 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:54 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand clear took 2 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "http://chmtunein.imbc.com/tchm/_definst_/tchm.stream/playlist.m3u8" Apr 12 13:17:54 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:54 volumio volumio[793]: info: Apr 12 13:17:54 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:54 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:54 volumio volumio[793]: info: ------------------------------ 3ms Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand add "http://chmtunein.imbc.com/tchm/_definst_/tchm.stream/playlist.m3u8" took 3 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:54 volumio volumio[793]: info: ------------------------------ 5ms Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand play took 4 milliseconds Apr 12 13:17:54 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: 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":"playlist.m3u8","artist":null,"album":null,"uri":"http://chmtunein.imbc.com/tchm/_definst_/tchm.stream/playlist.m3u8","trackType":"m3u8"} Apr 12 13:17:54 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:54 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:54 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:54 volumio volumio[793]: info: Apr 12 13:17:54 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:54 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:54 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:54 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:55 volumio volumio[793]: info: sendMpdCommand clearerror took 2 milliseconds Apr 12 13:17:55 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:17:55 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:55 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:55 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:55 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:55 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:55 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:55 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:55 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:55 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:55 volumio volumio[793]: info: ------------------------------ 17ms Apr 12 13:17:57 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:58 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:58 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:58 volumio volumio[793]: info: Adding Item to queue: websbs/0 Apr 12 13:17:58 volumio volumio[793]: info: Exploding uri websbs/0 in service personal_radio Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand stop took 30 milliseconds Apr 12 13:17:58 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: 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":"http://chmtunein.imbc.com/tchm/_definst_/tchm.stream/playlist.m3u8","trackType":"m3u8"} Apr 12 13:17:58 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:58 volumio volumio[793]: info: No code Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:58 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:58 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand stop took 8 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:58 volumio volumio[793]: info: Apr 12 13:17:58 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:58 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand clear took 3 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzgsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.bgxwI-j5MgYxDH6m41vTOjT9FwhTN4VWk8bouobrnJs" Apr 12 13:17:58 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:58 volumio volumio[793]: info: Apr 12 13:17:58 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:58 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:58 volumio volumio[793]: info: ------------------------------ 4ms Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand add "rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzgsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.bgxwI-j5MgYxDH6m41vTOjT9FwhTN4VWk8bouobrnJs" took 4 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:58 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand play took 4 milliseconds Apr 12 13:17:58 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:58 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:58 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: 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":"lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzgsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.bgxwI-j5MgYxDH6m41vTOjT9FwhTN4VWk8bouobrnJs","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzgsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.bgxwI-j5MgYxDH6m41vTOjT9FwhTN4VWk8bouobrnJs","trackType":""} Apr 12 13:17:58 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:58 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:58 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: Apr 12 13:17:59 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:17:59 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand status took 6 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand clearerror took 3 milliseconds Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:59 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:17:59 volumio volumio[793]: info: ------------------------------ 21ms Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:59 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:17:59 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:17:59 volumio volumio[793]: info: Adding Item to queue: websbs/1 Apr 12 13:17:59 volumio volumio[793]: info: Exploding uri websbs/1 in service personal_radio Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand stop took 28 milliseconds Apr 12 13:17:59 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: 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":"lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzgsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.bgxwI-j5MgYxDH6m41vTOjT9FwhTN4VWk8bouobrnJs","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/lovepc/_definst_/lovefm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzgsInBhdGgiOiIvbG92ZXBjL19kZWZpbnN0Xy9sb3ZlZm0uc3RyZWFtIiwiZHVyYXRpb24iOi0xfQ.bgxwI-j5MgYxDH6m41vTOjT9FwhTN4VWk8bouobrnJs","trackType":""} Apr 12 13:17:59 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: No code Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:17:59 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:59 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand stop took 7 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:17:59 volumio volumio[793]: info: Apr 12 13:17:59 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:59 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand clear took 2 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzksInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.WNjJo7wvgDNNy4AlaXpkHCj4FWbpgX71H-HiVu2JDhQ" Apr 12 13:17:59 volumio volumio[793]: error: updateQueue error: null Apr 12 13:17:59 volumio volumio[793]: info: Apr 12 13:17:59 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:17:59 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:17:59 volumio volumio[793]: info: ------------------------------ 5ms Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand add "rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzksInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.WNjJo7wvgDNNy4AlaXpkHCj4FWbpgX71H-HiVu2JDhQ" took 3 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:17:59 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand play took 4 milliseconds Apr 12 13:17:59 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:17:59 volumio volumio[793]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 12 13:17:59 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: 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":"powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzksInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.WNjJo7wvgDNNy4AlaXpkHCj4FWbpgX71H-HiVu2JDhQ","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzksInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.WNjJo7wvgDNNy4AlaXpkHCj4FWbpgX71H-HiVu2JDhQ","trackType":""} Apr 12 13:17:59 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:17:59 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:17:59 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:17:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:00 volumio volumio[793]: info: Apr 12 13:18:00 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:00 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 13:18:00 volumio volumio[793]: info: sendMpdCommand status took 5 milliseconds Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:00 volumio volumio[793]: info: sendMpdCommand clearerror took 3 milliseconds Apr 12 13:18:00 volumio volumio[793]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:00 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:00 volumio volumio[793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Apr 12 13:18:00 volumio volumio[793]: info: ------------------------------ 19ms Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:18:00 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:18:00 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:18:00 volumio volumio[793]: info: Adding Item to queue: websbs/2 Apr 12 13:18:00 volumio volumio[793]: info: Exploding uri websbs/2 in service personal_radio Apr 12 13:18:00 volumio volumio[793]: info: sendMpdCommand stop took 49 milliseconds Apr 12 13:18:00 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:00 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:00 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:18:00 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:00 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:00 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:00 volumio volumio[793]: 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":"powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzksInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.WNjJo7wvgDNNy4AlaXpkHCj4FWbpgX71H-HiVu2JDhQ","artist":null,"album":null,"uri":"rtmp://live.sbs.co.kr/powerpc/_definst_/powerfm.stream?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3NDQ1MDcwNzksInBhdGgiOiIvcG93ZXJwYy9fZGVmaW5zdF8vcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTF9.WNjJo7wvgDNNy4AlaXpkHCj4FWbpgX71H-HiVu2JDhQ","trackType":""} Apr 12 13:18:00 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:18:00 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:18:13 volumio systemd-journal[247]: Suppressed 350 messages from /system.slice/volumio.service Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:18:13 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:18:13 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:18:13 volumio volumio[793]: info: Adding Item to queue: weblinn/2 Apr 12 13:18:13 volumio volumio[793]: info: Exploding uri weblinn/2 in service personal_radio Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:18:13 volumio volumio[793]: info: CoreCommandRouter::volumioPlay Apr 12 13:18:13 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::play index 0 Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:18:13 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::play index undefined Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Apr 12 13:18:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand stop took 64 milliseconds Apr 12 13:18:13 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:13 volumio volumio[793]: info: Apr 12 13:18:13 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:13 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand stop took 45 milliseconds Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:13 volumio volumio[793]: info: Apr 12 13:18:13 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:18:13 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand clear took 2 milliseconds Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8004/stream" Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:13 volumio volumio[793]: error: updateQueue error: null Apr 12 13:18:13 volumio volumio[793]: info: Apr 12 13:18:13 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:18:13 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:18:13 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand add "http://radio.linnrecords.com:8004/stream" took 3 milliseconds Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:13 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand play Apr 12 13:18:13 volumio volumio[793]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 13:18:13 volumio volumio[793]: info: ------------------------------ 14ms Apr 12 13:18:13 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:18:13 volumio volumio[793]: info: sendMpdCommand play took 3 milliseconds Apr 12 13:18:13 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 13:18:14 volumio volumio[793]: info: Apr 12 13:18:14 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:14 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:14 volumio volumio[793]: info: Apr 12 13:18:14 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:14 volumio volumio[793]: info: sendMpdCommand status took 3 milliseconds Apr 12 13:18:14 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:14 volumio volumio[793]: info: Apr 12 13:18:14 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:18:14 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:18:14 volumio volumio[793]: info: Apr 12 13:18:14 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:18:14 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:18:14 volumio volumio[793]: info: Apr 12 13:18:14 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:14 volumio volumio[793]: info: sendMpdCommand status took 6 milliseconds Apr 12 13:18:14 volumio volumio[793]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 12 13:18:14 volumio volumio[793]: info: ------------------------------ 6ms Apr 12 13:18:14 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:14 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:18:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:14 volumio volumio[793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"stream","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Apr 12 13:18:14 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:14 volumio volumio[793]: info: ------------------------------ 23ms Apr 12 13:18:14 volumio volumio[793]: info: ------------------------------ 17ms Apr 12 13:18:14 volumio volumio[793]: info: sendMpdCommand status took 14 milliseconds Apr 12 13:18:14 volumio volumio[793]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:14 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:18:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:14 volumio volumio[793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Artur Pizarro and Vita Panomar - Scheherazade Op.35 Andantino q [100]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Apr 12 13:18:14 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:18:14 volumio volumio[793]: info: Received an update from plugin. extracting info from payload Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:14 volumio volumio[793]: info: ------------------------------ 38ms Apr 12 13:18:14 volumio volumio[793]: info: sendMpdCommand playlistinfo took 37 milliseconds Apr 12 13:18:14 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:14 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:18:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:14 volumio volumio[793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Artur Pizarro and Vita Panomar - Scheherazade Op.35 Andantino q [100]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Apr 12 13:18:14 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:18:14 volumio volumio[793]: info: Received an update from plugin. extracting info from payload Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:14 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:14 volumio volumio[793]: info: ------------------------------ 70ms Apr 12 13:18:25 volumio volumio[793]: info: Apr 12 13:18:25 volumio volumio[793]: ---------------------------- MPD announces system playlist update Apr 12 13:18:25 volumio volumio[793]: info: Ignoring MPD Status Update Apr 12 13:18:25 volumio volumio[793]: info: Apr 12 13:18:25 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:25 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:25 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:25 volumio volumio[793]: info: ------------------------------ 3ms Apr 12 13:18:25 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:18:25 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:25 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:25 volumio volumio[793]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 13:18:25 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:25 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:18:25 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:18:25 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:25 volumio volumio[793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Gothic Voices - Vergine donzella imperadrice","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Apr 12 13:18:25 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:25 volumio volumio[793]: info: CoreStateMachine::syncState stateService play Apr 12 13:18:25 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:18:25 volumio volumio[793]: info: Received an update from plugin. extracting info from payload Apr 12 13:18:25 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:25 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:25 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:25 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:25 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:25 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:25 volumio volumio[793]: info: ------------------------------ 21ms Apr 12 13:18:34 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::ClearQueue Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::stop Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 13:18:37 volumio volumio[793]: verbose: UNSET VOLATILE Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::stPlaybackTimer Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::serviceStop Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::serviceStop Apr 12 13:18:37 volumio volumio[793]: info: ControllerMpd::stop Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::clearPlayQueue Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::saveQueue Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushQueue Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::addQueueItems Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::addQueueItems Apr 12 13:18:37 volumio volumio[793]: info: Adding Item to queue: webkbs/5 Apr 12 13:18:37 volumio volumio[793]: info: Exploding uri webkbs/5 in service personal_radio Apr 12 13:18:37 volumio volumio[793]: info: sendMpdCommand stop took 83 milliseconds Apr 12 13:18:37 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:37 volumio volumio[793]: info: Apr 12 13:18:37 volumio volumio[793]: ---------------------------- MPD announces state update: player Apr 12 13:18:37 volumio volumio[793]: info: ControllerMpd::getState Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:18:37 volumio volumio[793]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:37 volumio volumio[793]: info: sendMpdCommand status took 12 milliseconds Apr 12 13:18:37 volumio volumio[793]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::parseState Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: 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":"Gothic Voices - Vergine donzella imperadrice","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Apr 12 13:18:37 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: No code Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 12 13:18:37 volumio volumio[793]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:18:37 volumio volumio[793]: info: ControllerMpd::pushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::servicePushState Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: 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":"Gothic Voices - Vergine donzella imperadrice","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Apr 12 13:18:37 volumio volumio[793]: verbose: CURRENT POSITION 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: No code Apr 12 13:18:37 volumio volumio[793]: info: CoreStateMachine::pushState Apr 12 13:18:37 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Apr 12 13:18:37 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:18:37 volumio volumio[793]: info: ------------------------------ 65ms Apr 12 13:18:37 volumio volumio[793]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 13:18:37 volumio volumio[793]: ReferenceError: self is not defined Apr 12 13:18:37 volumio volumio[793]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Apr 12 13:18:37 volumio volumio[793]: at /data/plugins/music_service/personal_radio/index.js:487:14 Apr 12 13:18:37 volumio volumio[793]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Apr 12 13:18:37 volumio volumio[793]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Apr 12 13:18:37 volumio volumio[793]: at emitOne (events.js:116:13) Apr 12 13:18:37 volumio volumio[793]: at Request.emit (events.js:211:7) Apr 12 13:18:37 volumio volumio[793]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Apr 12 13:18:37 volumio volumio[793]: at emitOne (events.js:116:13) Apr 12 13:18:37 volumio volumio[793]: at ClientRequest.emit (events.js:211:7) Apr 12 13:18:37 volumio volumio[793]: at Socket.socketErrorListener (_http_client.js:387:9) Apr 12 13:18:37 volumio volumio[793]: at emitOne (events.js:116:13) Apr 12 13:18:37 volumio volumio[793]: at Socket.emit (events.js:211:7) Apr 12 13:18:37 volumio volumio[793]: at emitErrorNT (internal/streams/destroy.js:64:8) Apr 12 13:18:37 volumio volumio[793]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Apr 12 13:18:37 volumio volumio[793]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 12 13:18:37 volumio volumio[793]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 13:18:38 volumio sudo[5065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-12 13:17 Apr 12 13:18:38 volumio sudo[5065]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"