Dec 12 22:02:02 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::ClearQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::stop Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::stPlaybackTimer Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::updateTrackBlock Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrackBlock Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::serviceStop Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::serviceStop Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::stop Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::clearPlayQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::saveQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::addQueueItems Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::addQueueItems Dec 12 22:02:02 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/TBS?%E5%95%8F%E3%82%8F%E3%81%9A%E8%AA%9E%E3%82%8A%E3%81%AE%E7%A5%9E%E7%94%B0%E4%BC%AF%E5%B1%B1&%E7%A5%9E%E7%94%B0%E4%BC%AF%E5%B1%B1&TBS%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2021%3A30-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FTBS_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/TBS?%E5%95%8F%E3%82%8F%E3%81%9A%E8%AA%9E%E3%82%8A%E3%81%AE%E7%A5%9E%E7%94%B0%E4%BC%AF%E5%B1%B1&%E7%A5%9E%E7%94%B0%E4%BC%AF%E5%B1%B1&TBS%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2021%3A30-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FTBS_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/QRR?%E6%96%89%E8%97%A4%E5%A3%AE%E9%A6%AC%20Strange%20dayS&%E6%96%89%E8%97%A4%E5%A3%AE%E9%A6%AC&%E6%96%87%E5%8C%96%E6%94%BE%E9%80%81%20%2F%2021%3A30-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FQRR_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/QRR?%E6%96%89%E8%97%A4%E5%A3%AE%E9%A6%AC%20Strange%20dayS&%E6%96%89%E8%97%A4%E5%A3%AE%E9%A6%AC&%E6%96%87%E5%8C%96%E6%94%BE%E9%80%81%20%2F%2021%3A30-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FQRR_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/LFR?%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%81%AE%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3GOLD%EF%BD%9E%E3%82%AA%E3%83%BC%E3%83%AB%E3%82%BF%E3%82%A4%E3%83%A0%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%EF%BD%9E&%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%80%81%E3%83%91%E3%83%BC%E3%83%88%E3%83%8A%E3%83%BC%EF%BC%9A%E3%83%9F%E3%83%83%E3%83%84%E3%83%BB%E3%83%9E%E3%83%B3%E3%82%B0%E3%83%AD%E3%83%BC%E3%83%96%E3%80%81%E5%9E%A3%E8%8A%B1%E6%AD%A3&%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3%E6%94%BE%E9%80%81%20%2F%2020%3A00-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FLFR_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/LFR?%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%81%AE%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3GOLD%EF%BD%9E%E3%82%AA%E3%83%BC%E3%83%AB%E3%82%BF%E3%82%A4%E3%83%A0%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%EF%BD%9E&%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%80%81%E3%83%91%E3%83%BC%E3%83%88%E3%83%8A%E3%83%BC%EF%BC%9A%E3%83%9F%E3%83%83%E3%83%84%E3%83%BB%E3%83%9E%E3%83%B3%E3%82%B0%E3%83%AD%E3%83%BC%E3%83%96%E3%80%81%E5%9E%A3%E8%8A%B1%E6%AD%A3&%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3%E6%94%BE%E9%80%81%20%2F%2020%3A00-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FLFR_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/INT?interfm%20Headline%20News&&interfm%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FINT_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/INT?interfm%20Headline%20News&&interfm%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FINT_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/FMT?TOKYO%20FM%20NEWS&&TOKYO%20FM%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMT_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/FMT?TOKYO%20FM%20NEWS&&TOKYO%20FM%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMT_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/FMJ?INNOVATION%20WORLD&%E5%B7%9D%E7%94%B0%E5%8D%81%E5%A4%A2&J-WAVE%20%2F%2020%3A00-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMJ_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/FMJ?INNOVATION%20WORLD&%E5%B7%9D%E7%94%B0%E5%8D%81%E5%A4%A2&J-WAVE%20%2F%2020%3A00-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMJ_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/JORF?%E6%96%B0%E6%9B%B2EXPRESS!!&%E6%B2%B3%E6%9D%91%E7%94%B1%E7%BE%8E&%E3%83%A9%E3%82%B8%E3%82%AA%E6%97%A5%E6%9C%AC%20%2F%2021%3A45-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJORF_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/JORF?%E6%96%B0%E6%9B%B2EXPRESS!!&%E6%B2%B3%E6%9D%91%E7%94%B1%E7%BE%8E&%E3%83%A9%E3%82%B8%E3%82%AA%E6%97%A5%E6%9C%AC%20%2F%2021%3A45-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJORF_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/BAYFM78?%E6%A3%AE%E4%B9%85%E4%BF%9D%E7%A5%A5%E5%A4%AA%E9%83%8E%E3%81%AE%E4%BB%8A%E9%80%B1%E3%82%8F%E3%81%9A&%E6%A3%AE%E4%B9%85%E4%BF%9D%20%E7%A5%A5%E5%A4%AA%E9%83%8E%20%2F%20%E5%B0%8F%E5%9D%82%20%E7%9C%9F%E7%90%B4&BAYFM78%20%2F%2021%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FBAYFM78_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/BAYFM78?%E6%A3%AE%E4%B9%85%E4%BF%9D%E7%A5%A5%E5%A4%AA%E9%83%8E%E3%81%AE%E4%BB%8A%E9%80%B1%E3%82%8F%E3%81%9A&%E6%A3%AE%E4%B9%85%E4%BF%9D%20%E7%A5%A5%E5%A4%AA%E9%83%8E%20%2F%20%E5%B0%8F%E5%9D%82%20%E7%9C%9F%E7%90%B4&BAYFM78%20%2F%2021%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FBAYFM78_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/NACK5?K's%20TRANSMISSION&%E5%9D%82%E5%B4%8E%E5%B9%B8%E4%B9%8B%E5%8A%A9%EF%BC%88THE%20ALFEE%EF%BC%89&NACK5%20%2F%2021%3A00-23%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FNACK5_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/NACK5?K's%20TRANSMISSION&%E5%9D%82%E5%B4%8E%E5%B9%B8%E4%B9%8B%E5%8A%A9%EF%BC%88THE%20ALFEE%EF%BC%89&NACK5%20%2F%2021%3A00-23%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FNACK5_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::saveQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::updateTrackBlock Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrackBlock Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPlay Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::play index 8 Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::addQueueItems Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::addQueueItems Dec 12 22:02:02 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/YFM?U-MORE%EF%BC%81&%E9%88%B4%E6%9C%A8%E8%A3%95%E4%BB%8B&%EF%BC%A6%EF%BC%AD%E3%83%A8%E3%82%B3%E3%83%8F%E3%83%9E%20%2F%2019%3A00-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FYFM_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/YFM?U-MORE%EF%BC%81&%E9%88%B4%E6%9C%A8%E8%A3%95%E4%BB%8B&%EF%BC%A6%EF%BC%AD%E3%83%A8%E3%82%B3%E3%83%8F%E3%83%9E%20%2F%2019%3A00-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FYFM_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/IBS?LuckyFM%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9%E3%83%BB%E5%A4%A9%E6%B0%97%E4%BA%88%E5%A0%B1&&LuckyFM%20%E8%8C%A8%E5%9F%8E%E6%94%BE%E9%80%81%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FIBS_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/IBS?LuckyFM%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9%E3%83%BB%E5%A4%A9%E6%B0%97%E4%BA%88%E5%A0%B1&&LuckyFM%20%E8%8C%A8%E5%9F%8E%E6%94%BE%E9%80%81%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FIBS_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: Adding Item to queue: radiko/play/JOAK?%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9%E3%83%BB%E6%B5%B7%E4%B8%8A%E6%B0%97%E8%B1%A1%E6%83%85%E5%A0%B1%E3%83%BB%E4%BA%A4%E9%80%9A%E6%83%85%E5%A0%B1%EF%BC%88%E9%96%A2%E6%9D%B1%E7%94%B2%E4%BF%A1%E8%B6%8A%EF%BC%89&&NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E6%9D%B1%E4%BA%AC%EF%BC%89%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJOAK_logo.png Dec 12 22:02:02 volumio42 volumio[1232]: info: Exploding uri radiko/play/JOAK?%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9%E3%83%BB%E6%B5%B7%E4%B8%8A%E6%B0%97%E8%B1%A1%E6%83%85%E5%A0%B1%E3%83%BB%E4%BA%A4%E9%80%9A%E6%83%85%E5%A0%B1%EF%BC%88%E9%96%A2%E6%9D%B1%E7%94%B2%E4%BF%A1%E8%B6%8A%EF%BC%89&&NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E6%9D%B1%E4%BA%AC%EF%BC%89%20%2F%2021%3A55-22%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJOAK_logo.png in service jp_radio Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::stop Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::play index undefined Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 8 Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::startPlaybackTimer Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 8 Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::clearAddPlayTrack: uri=http://localhost:9000/radiko/play/NACK5 Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::saveQueue Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::updateTrackBlock Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrackBlock Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand stop took 84 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand stop took 33 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:02 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:02 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: res.on(close) Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: SIGTERM sent to ffmpeg group 6754 Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:02:02 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand status took 11 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand clear took 11 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand status took 9 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand status took 7 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/NACK5" Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:02 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:02 volumio42 volumio[1232]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Dec 12 22:02:02 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:02 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:02 volumio42 volumio[1232]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 61ms Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 60ms Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:02:02 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:02:02 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:02:02 volumio42 volumio[1232]: error: updateQueue error: null Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:02:02 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 64ms Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand add "http://localhost:9000/radiko/play/NACK5" took 51 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 9ms Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 8ms Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:02 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 22:02:02 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand play Dec 12 22:02:02 volumio42 volumio[1232]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 78ms Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:02:02 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:02:02 volumio42 volumio[1232]: info: Dec 12 22:02:02 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:02:02 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 30ms Dec 12 22:02:02 volumio42 volumio[1232]: info: sendMpdCommand play took 25 milliseconds Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 23ms Dec 12 22:02:02 volumio42 volumio[1232]: info: ------------------------------ 21ms Dec 12 22:02:02 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::JpRadio.#setupRoutes.get=> req.url=/radiko/play/NACK5 Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: stationId=NACK5, query=[] Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::Radiko.play: url=https://f-radiko.smartstream.ne.jp/NACK5/_definst_/simul-stream.stream/playlist.m3u8 Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: ffmpeg.pid=6809 Dec 12 22:02:02 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: Streaming started Dec 12 22:02:05 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:05 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:05 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"title":"K's TRANSMISSION - 坂崎幸之助（THE ALFEE）","artist":"NACK5 / 21:00-23:00 (Live)","album":null,"albumart":"/albumart?sourceicon=music_service/jp_radio/dist/assets/images/RN2_logo.png","uri":"http://localhost:9000/radiko/play/JOAK-FM","trackType":"webradio","seek":3705000,"duration":7200,"samplerate":"","bitdepth":"","channels":2,"bitrate":"48 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":90,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Dec 12 22:02:05 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:05 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:05 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Dec 12 22:02:05 volumio42 volumio[1232]: info: JP_Radio::RdkProg.clearOldProgram: Removed 0 documents from DB Dec 12 22:02:05 volumio42 volumio[1232]: info: JP_Radio::RdkProg.dbCount: 290 Dec 12 22:02:05 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:02:06 volumio42 volumio[1232]: info: Dec 12 22:02:06 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:06 volumio42 volumio[1232]: info: Dec 12 22:02:06 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:06 volumio42 volumio[1232]: info: Dec 12 22:02:06 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:06 volumio42 volumio[1232]: info: Dec 12 22:02:06 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand status took 28 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand status took 26 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:06 volumio42 volumio[1232]: info: Dec 12 22:02:06 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:06 volumio42 volumio[1232]: info: Dec 12 22:02:06 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand status took 33 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand status took 10 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NACK5","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/NACK5","trackType":""} Dec 12 22:02:06 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:06 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NACK5","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/NACK5","trackType":""} Dec 12 22:02:06 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:06 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: ------------------------------ 123ms Dec 12 22:02:06 volumio42 volumio[1232]: info: ------------------------------ 123ms Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand status took 94 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand status took 92 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 87 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 87 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NACK5","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/NACK5","trackType":""} Dec 12 22:02:06 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:06 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NACK5","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/NACK5","trackType":""} Dec 12 22:02:06 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:06 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: ------------------------------ 199ms Dec 12 22:02:06 volumio42 volumio[1232]: info: ------------------------------ 178ms Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 132 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: info: sendMpdCommand playlistinfo took 131 milliseconds Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:06 volumio42 volumio[1232]: verbose: ControllerMpd::parseTrackInfo Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NACK5","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/NACK5","trackType":""} Dec 12 22:02:06 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:06 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NACK5","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/NACK5","trackType":""} Dec 12 22:02:06 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:06 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:06 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:06 volumio42 volumio[1232]: info: ------------------------------ 290ms Dec 12 22:02:06 volumio42 volumio[1232]: info: ------------------------------ 288ms Dec 12 22:02:10 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:12 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 12 22:02:12 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:13 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 12 22:02:13 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:18 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:02:21 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:02:21 volumio42 volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"title":"K's TRANSMISSION - 坂崎幸之助（THE ALFEE）","artist":"NACK5 / 21:00-23:00 (Live)","album":null,"albumart":"/albumart?sourceicon=music_service/jp_radio/dist/assets/images/RN2_logo.png","uri":"http://localhost:9000/radiko/play/NACK5","trackType":"webradio","seek":3721000,"duration":7200,"samplerate":"","bitdepth":"","channels":2,"bitrate":null,"random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":90,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Dec 12 22:02:21 volumio42 volumio[1232]: verbose: CURRENT POSITION 0 Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreStateMachine::syncState stateService play Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreStateMachine::syncState currentStatus play Dec 12 22:02:21 volumio42 volumio[1232]: info: Received an update from plugin. extracting info from payload Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:02:21 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:02:21 volumio42 volumio[1232]: info: JP_Radio::RdkProg.clearOldProgram: Removed 0 documents from DB Dec 12 22:02:21 volumio42 volumio[1232]: info: JP_Radio::RdkProg.dbCount: 290 Dec 12 22:02:24 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Dec 12 22:02:24 volumio42 volumio[1232]: info: JP_Radio::handleBrowseUri: curUri=radiko Dec 12 22:02:24 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:26 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Dec 12 22:02:26 volumio42 volumio[1232]: info: JP_Radio::handleBrowseUri: curUri=radiko/live Dec 12 22:02:26 volumio42 volumio[1232]: info: JP_Radio::JpRadio.radioStations: live Dec 12 22:02:26 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/IBC?%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3%EF%BC%A7%EF%BC%AF%EF%BC%AC%EF%BC%A4&%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%20%2F%20%E3%83%9F%E3%83%83%E3%83%84%E3%83%BB%E3%83%9E%E3%83%B3%E3%82%B0%E3%83%AD%E3%83%BC%E3%83%96%20%2F%20%E5%9E%A3%E8%8A%B1%E6%AD%A3&IBC%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2022%3A00-24%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FIBC_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/FMI?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B2%A9%E6%89%8B%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMI_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/TBC?%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%81%AE%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3%EF%BC%A7%EF%BC%AF%EF%BC%AC%EF%BC%A4%EF%BD%9E%E3%82%AA%E3%83%BC%E3%83%AB%E3%82%BF%E3%82%A4%E3%83%A0%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%EF%BD%9E&%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C&TBC%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2022%3A00-24%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FTBC_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/DATEFM?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&Date%20fm%20%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E4%BB%99%E5%8F%B0%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FDATEFM_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/YBC?%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%81%AE%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3GOLD%EF%BD%9E%E3%82%AA%E3%83%BC%E3%83%AB%E3%82%BF%E3%82%A4%E3%83%A0%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%EF%BD%9E&&YBC%E5%B1%B1%E5%BD%A2%E6%94%BE%E9%80%81%20%2F%2022%3A00-24%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FYBC_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/RFM?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&Rhythm%20Station%E3%80%80%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%BD%A2%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FRFM_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/RFC?%E4%BB%96%E8%A8%80%E7%84%A1%E7%94%A8%EF%BD%9E%E5%A5%B3%E6%80%A7%E3%82%A2%E3%83%8A%E3%82%A6%E3%83%B3%E3%82%B5%E3%83%BC%E3%81%8C%E5%A5%BD%E3%81%8D%E5%8B%9D%E6%89%8B%E3%81%97%E3%81%BE%E3%81%99%EF%BC%81%EF%BD%9E&&RFC%E3%83%A9%E3%82%B8%E3%82%AA%E7%A6%8F%E5%B3%B6%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FRFC_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/FMF?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&%E3%81%B5%E3%81%8F%E3%81%97%E3%81%BEFM%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMF_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/JOHK?%EF%BC%AE%EF%BC%A8%EF%BC%AB%E3%82%B8%E3%83%A3%E3%83%BC%E3%83%8A%E3%83%AB&%E5%A0%A4%E5%8D%83%E6%98%A5(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)%2F%E5%8C%97%E9%87%8E%E5%89%9B%E5%AF%9B(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)%2F%E6%9D%91%E5%B2%A1%E5%AD%9D%E5%BD%A6(%E8%A7%A3%E8%AA%AC)%2F%E4%BD%90%E8%97%A4%E5%8F%AF%E5%A5%88%E5%AD%90(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)&NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E4%BB%99%E5%8F%B0%EF%BC%89%20%2F%2022%3A00-22%3A55&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJOHK_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/TBS?%E6%AD%A6%E7%94%B0%E7%A0%82%E9%89%84%E3%81%AE%E3%83%97%E3%83%AC%E9%87%91%E3%83%8A%E3%82%A4%E3%83%88&%E6%AD%A6%E7%94%B0%E7%A0%82%E9%89%84%E3%80%80%E3%82%B2%E3%82%B9%E3%83%88%EF%BC%9A%E9%BB%92%E6%B2%A2%E3%81%8B%E3%81%9A%E3%81%93&TBS%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2022%3A00-23%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FTBS_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/QRR?E%EF%BC%81Comic%20Jam&%E3%83%A9%E3%83%B3%E3%82%BA%E3%83%99%E3%83%AA%E3%83%BC%E3%83%BB%E3%82%A2%E3%83%BC%E3%82%B5%E3%83%BC%2C%20%E8%97%A4%E6%BE%A4%E5%A5%A8&%E6%96%87%E5%8C%96%E6%94%BE%E9%80%81%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FQRR_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/LFR?%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%81%AE%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3GOLD%EF%BD%9E%E3%82%AA%E3%83%BC%E3%83%AB%E3%82%BF%E3%82%A4%E3%83%A0%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%EF%BD%9E%20Part2&%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%E3%80%81%E3%83%91%E3%83%BC%E3%83%88%E3%83%8A%E3%83%BC%EF%BC%9A%E3%83%9F%E3%83%83%E3%83%84%E3%83%BB%E3%83%9E%E3%83%B3%E3%82%B0%E3%83%AD%E3%83%BC%E3%83%96%E3%80%81%E5%9E%A3%E8%8A%B1%E6%AD%A3&%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3%E6%94%BE%E9%80%81%20%2F%2022%3A00-24%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FLFR_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/INT?Tokyo%20Crossover%20Radio&%E6%B2%96%E9%87%8E%E4%BF%AE%E4%B9%9F%EF%BC%88Kyoto%20Jazz%20Massive%20%2F%20Kyoto%20Jazz%20Sextet%EF%BC%89&interfm%20%2F%2022%3A00-23%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FINT_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/FMT?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&TOKYO%20FM%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMT_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/FMJ?MUSIC%20BLOOM&Shin%20Sakiura%20%2F%20Rachel%EF%BC%88chelmico%EF%BC%89&J-WAVE%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMJ_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/JORF?%E3%81%8D%E3%81%AE%E3%81%86%E3%81%AE%E7%B6%9A%E3%81%8D%E3%81%AE%E3%81%A4%E3%81%A5%E3%81%8D&%E5%8C%97%E5%8E%9F%E7%85%A7%E4%B9%85%E3%80%80%E6%9C%9D%E5%80%89%E5%A5%88%E3%80%85&%E3%83%A9%E3%82%B8%E3%82%AA%E6%97%A5%E6%9C%AC%20%2F%2022%3A00-22%3A15&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJORF_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/YFM?ZERO-8&REIJI%20%2F%20%E5%85%AB%E6%9D%91%E5%80%AB%E5%A4%AA%E9%83%8E(WATWING)&%EF%BC%A6%EF%BC%AD%E3%83%A8%E3%82%B3%E3%83%8F%E3%83%9E%20%2F%2022%3A00-23%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FYFM_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/IBS?%E4%BB%8A%E5%A4%9C%E3%81%AFLucky%20Night%EF%BD%9E%E3%82%8A%E3%81%BB%E3%81%AA%E3%82%A2%E3%83%8B%E3%82%BD%E3%83%B3%E3%83%95%E3%83%A9%E3%82%A4%E3%83%87%E3%83%BC%EF%BD%9E&%E5%8A%A0%E8%97%A4%E9%87%8C%E4%BF%9D%E8%8F%9C&LuckyFM%20%E8%8C%A8%E5%9F%8E%E6%94%BE%E9%80%81%20%2F%2022%3A00-24%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FIBS_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/JOAK?%EF%BC%AE%EF%BC%A8%EF%BC%AB%E3%82%B8%E3%83%A3%E3%83%BC%E3%83%8A%E3%83%AB&%E5%A0%A4%E5%8D%83%E6%98%A5(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)%2F%E5%8C%97%E9%87%8E%E5%89%9B%E5%AF%9B(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)%2F%E6%9D%91%E5%B2%A1%E5%AD%9D%E5%BD%A6(%E8%A7%A3%E8%AA%AC)%2F%E4%BD%90%E8%97%A4%E5%8F%AF%E5%A5%88%E5%AD%90(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)&NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E6%9D%B1%E4%BA%AC%EF%BC%89%20%2F%2022%3A00-22%3A55&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJOAK_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/RKB?%EF%BC%83%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%91%E3%83%AC%E3%80%80%E6%9C%8D%E9%83%A8%E3%81%95%E3%82%84%E3%81%8B%E3%81%AE%E3%82%B7%E3%83%A5%E3%83%B3%E3%81%99%E3%81%8E&%E6%9C%8D%E9%83%A8%E3%81%95%E3%82%84%E3%81%8B&RKB%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2021%3A00-23%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FRKB_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/KBC?%E3%82%AA%E3%83%BC%E3%83%AB%E3%83%8A%E3%82%A4%E3%83%88%E3%83%8B%E3%83%83%E3%83%9D%E3%83%B3%EF%BC%A7%EF%BC%AF%EF%BC%AC%EF%BC%A4&%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C&KBC%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2022%3A00-24%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FKBC_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/LOVEFM?%E5%B8%B8%E7%9B%A4%E9%9F%BF%E3%81%AE%E3%83%8B%E3%83%A5%E3%83%BC%E3%83%AC%E3%82%B3%E3%83%BC%E3%83%89&%E5%B8%B8%E7%9B%A4%20%E9%9F%BF&LOVE%20FM%20%2F%2022%3A00-23%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FLOVEFM_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/CROSSFM?V%EF%BD%A5A%EF%BD%A5D%20weekend&MASAKI&CROSS%20FM%20%2F%2020%3A00-23%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FCROSSFM_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/FMFUKUOKA?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&FM%20FUKUOKA%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMFUKUOKA_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/RBC?%E8%8F%8A%E5%9C%B0%E5%BF%97%E4%B9%83%E3%81%AEVilla%20de%20Weekend&%E8%8F%8A%E5%9C%B0%E5%BF%97%E4%B9%83&RBCi%E3%83%A9%E3%82%B8%E3%82%AA%20%2F%2021%3A15-23%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FRBC_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/ROK?Watta!%20Itta!&%E3%82%AF%E3%83%AF%E3%82%A8%E3%83%AA%E3%83%A7%E3%82%A6&%E3%83%A9%E3%82%B8%E3%82%AA%E6%B2%96%E7%B8%84%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FROK_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/FM_OKINAWA?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&FM%E6%B2%96%E7%B8%84%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFM_OKINAWA_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/JOLK?%EF%BC%AE%EF%BC%A8%EF%BC%AB%E3%82%B8%E3%83%A3%E3%83%BC%E3%83%8A%E3%83%AB&%E5%A0%A4%E5%8D%83%E6%98%A5(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)%2F%E5%8C%97%E9%87%8E%E5%89%9B%E5%AF%9B(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)%2F%E6%9D%91%E5%B2%A1%E5%AD%9D%E5%BD%A6(%E8%A7%A3%E8%AA%AC)%2F%E4%BD%90%E8%97%A4%E5%8F%AF%E5%A5%88%E5%AD%90(%E3%82%AD%E3%83%A3%E3%82%B9%E3%82%BF%E3%83%BC)&NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E7%A6%8F%E5%B2%A1%EF%BC%89%20%2F%2022%3A00-22%3A55&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJOLK_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:26 volumio42 volumio[1232]: info: Preloading song: radiko/play/RN1?%E7%94%BA%E7%94%B0%E5%BE%B9%E3%81%AE%E3%81%B5%E3%81%8B%E3%81%BC%E3%82%8A%EF%BC%81&%E7%94%BA%E7%94%B0%E5%BE%B9%20%2F%20%E6%9D%89%E6%B5%A6%E8%88%9E&%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC1%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FRN1_logo.png Dec 12 22:02:26 volumio42 volumio[1232]: info: Exploding uri radiko/play/RN1?%E7%94%BA%E7%94%B0%E5%BE%B9%E3%81%AE%E3%81%B5%E3%81%8B%E3%81%BC%E3%82%8A%EF%BC%81&%E7%94%BA%E7%94%B0%E5%BE%B9%20%2F%20%E6%9D%89%E6%B5%A6%E8%88%9E&%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC1%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FRN1_logo.png in service jp_radio Dec 12 22:02:26 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:35 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:36 volumio42 volumio[1232]: info: Adding radiko/play/FMT?SCHOOL%20OF%20LOCK!%20FRIDAY%20%E5%AD%A6%E6%A0%A1%E9%81%8B%E5%96%B6%E6%88%A6%E7%95%A5%E4%BC%9A%E8%AD%B0&%E3%81%93%E3%82%82%E3%82%8A%E6%A0%A1%E9%95%B7%20%2F%20%E3%82%A2%E3%83%B3%E3%82%B8%E3%83%BC%E6%95%99%E9%A0%AD&TOKYO%20FM%20%2F%2022%3A00-22%3A30&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FFMT_logo.png to favourites with specific jp_radio method Dec 12 22:02:36 volumio42 volumio[1232]: info: JP_Radio::addToFavourites: item=service,jp_radio,type,track,name,SCHOOL OF LOCK! FRIDAY 学校運営戦略会議,album,こもり校長 / アンジー教頭,artist,TOKYO FM / 22:00-22:30,albumart,/albumart?sourceicon=music_service/jp_radio/dist/assets/images/FMT_logo.png,uri,http://localhost:9000/radiko/play/FMT Dec 12 22:02:36 volumio42 volumio[1232]: info: Saving local item radio-favourites Dec 12 22:02:36 volumio42 volumio[1232]: info: Pushing Favourites {"uri":"http://localhost:9000/radiko/play/FMT","favourite":false} Dec 12 22:02:41 volumio42 volumio[1232]: info: Adding radiko/play/INT?Tokyo%20Crossover%20Radio&%E6%B2%96%E9%87%8E%E4%BF%AE%E4%B9%9F%EF%BC%88Kyoto%20Jazz%20Massive%20%2F%20Kyoto%20Jazz%20Sextet%EF%BC%89&interfm%20%2F%2022%3A00-23%3A00&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FINT_logo.png to favourites with specific jp_radio method Dec 12 22:02:41 volumio42 volumio[1232]: info: JP_Radio::addToFavourites: item=service,jp_radio,type,track,name,Tokyo Crossover Radio,album,沖野修也（Kyoto Jazz Massive / Kyoto Jazz Sextet）,artist,interfm / 22:00-23:00,albumart,/albumart?sourceicon=music_service/jp_radio/dist/assets/images/INT_logo.png,uri,http://localhost:9000/radiko/play/INT Dec 12 22:02:41 volumio42 volumio[1232]: info: Saving local item radio-favourites Dec 12 22:02:41 volumio42 volumio[1232]: info: Pushing Favourites {"uri":"http://localhost:9000/radiko/play/INT","favourite":false} Dec 12 22:02:43 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:48 volumio42 volumio[1232]: info: Adding radiko/play/JORF?%E3%81%8D%E3%81%AE%E3%81%86%E3%81%AE%E7%B6%9A%E3%81%8D%E3%81%AE%E3%81%A4%E3%81%A5%E3%81%8D&%E5%8C%97%E5%8E%9F%E7%85%A7%E4%B9%85%E3%80%80%E6%9C%9D%E5%80%89%E5%A5%88%E3%80%85&%E3%83%A9%E3%82%B8%E3%82%AA%E6%97%A5%E6%9C%AC%20%2F%2022%3A00-22%3A15&%2Falbumart%3Fsourceicon%3Dmusic_service%2Fjp_radio%2Fdist%2Fassets%2Fimages%2FJORF_logo.png to favourites with specific jp_radio method Dec 12 22:02:48 volumio42 volumio[1232]: info: JP_Radio::addToFavourites: item=service,jp_radio,type,track,name,きのうの続きのつづき,album,北原照久　朝倉奈々,artist,ラジオ日本 / 22:00-22:15,albumart,/albumart?sourceicon=music_service/jp_radio/dist/assets/images/JORF_logo.png,uri,http://localhost:9000/radiko/play/JORF Dec 12 22:02:48 volumio42 volumio[1232]: info: Saving local item radio-favourites Dec 12 22:02:48 volumio42 volumio[1232]: info: Pushing Favourites {"uri":"http://localhost:9000/radiko/play/JORF","favourite":false} Dec 12 22:02:51 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:02:56 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 12 22:02:56 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:57 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 12 22:02:58 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:02:59 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:03:07 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:03:12 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::ClearQueue Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::stop Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::stPlaybackTimer Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::updateTrackBlock Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrackBlock Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::serviceStop Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::serviceStop Dec 12 22:03:12 volumio42 volumio[1232]: info: JP_Radio::stop Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::clearPlayQueue Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::saveQueue Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::addQueueItems Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::addQueueItems Dec 12 22:03:12 volumio42 volumio[1232]: info: Preload queue cleared Dec 12 22:03:12 volumio42 volumio[1232]: info: Adding Item to queue: http://localhost:9000/radiko/play/JORF Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::saveQueue Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::updateTrackBlock Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrackBlock Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPlay Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::play index 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::stop Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::play index undefined Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::startPlaybackTimer Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: [1765544592686] ControllerWebradio::clearAddPlayTrack Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 22:03:12 volumio42 volumio[1232]: info: Dec 12 22:03:12 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:03:12 volumio42 volumio[1232]: info: sendMpdCommand stop took 49 milliseconds Dec 12 22:03:12 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:03:12 volumio42 volumio[1232]: info: sendMpdCommand stop took 16 milliseconds Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 22:03:12 volumio42 volumio[1232]: info: Dec 12 22:03:12 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:03:12 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:03:12 volumio42 volumio[1232]: info: Dec 12 22:03:12 volumio42 volumio[1232]: ---------------------------- MPD announces state update: player Dec 12 22:03:12 volumio42 volumio[1232]: info: ControllerMpd::getState Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Dec 12 22:03:12 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: res.on(close) Dec 12 22:03:12 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: SIGTERM sent to ffmpeg group 6809 Dec 12 22:03:12 volumio42 volumio[1232]: info: Dec 12 22:03:12 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:03:12 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:03:12 volumio42 volumio[1232]: info: sendMpdCommand status took 13 milliseconds Dec 12 22:03:12 volumio42 volumio[1232]: info: sendMpdCommand clear took 12 milliseconds Dec 12 22:03:12 volumio42 volumio[1232]: info: sendMpdCommand status took 9 milliseconds Dec 12 22:03:12 volumio42 volumio[1232]: info: sendMpdCommand status took 7 milliseconds Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::sendMpdCommand load "http://localhost:9000/radiko/play/JORF" Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:03:12 volumio42 volumio[1232]: verbose: ControllerMpd::parseState Dec 12 22:03:12 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 12 22:03:12 volumio42 volumio[1232]: info: ControllerMpd::pushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::servicePushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreStateMachine::pushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: CoreCommandRouter::volumioPushState Dec 12 22:03:12 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:12 volumio42 volumio[1232]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 12 22:03:12 volumio42 volumio[1232]: info: ------------------------------ 56ms Dec 12 22:03:12 volumio42 volumio[1232]: info: ------------------------------ 55ms Dec 12 22:03:12 volumio42 volumio[1232]: info: Dec 12 22:03:12 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:03:12 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:03:12 volumio42 volumio[1232]: info: Dec 12 22:03:12 volumio42 volumio[1232]: ---------------------------- MPD announces system playlist update Dec 12 22:03:12 volumio42 volumio[1232]: info: Ignoring MPD Status Update Dec 12 22:03:12 volumio42 volumio[1232]: error: updateQueue error: null Dec 12 22:03:12 volumio42 volumio[1232]: info: ------------------------------ 55ms Dec 12 22:03:12 volumio42 volumio[1232]: info: JP_Radio::JpRadio.#setupRoutes.get=> req.url=/radiko/play/JORF Dec 12 22:03:12 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: stationId=JORF, query=[] Dec 12 22:03:12 volumio42 volumio[1232]: info: JP_Radio::Radiko.play: url=https://f-radiko.smartstream.ne.jp/JORF/_definst_/simul-stream.stream/playlist.m3u8 Dec 12 22:03:13 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: ffmpeg.pid=7070 Dec 12 22:03:13 volumio42 volumio[1232]: info: JP_Radio::JpRadio.startStream: Streaming started Dec 12 22:03:15 volumio42 volumio[1232]: info: Discovery: Remote connection error: 192.168.10.110 Dec 12 22:03:16 volumio42 volumio[1232]: info: CorePlayQueue::getTrack 0 Dec 12 22:03:16 volumio42 volumio[1232]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 22:03:16 volumio42 volumio[1232]: TypeError: Cannot read properties of undefined (reading 'endsWith') Dec 12 22:03:16 volumio42 volumio[1232]: at JpRadio.updateQueueInfo (/data/plugins/music_service/jp_radio/dist/lib/radio.js:161:37) Dec 12 22:03:16 volumio42 volumio[1232]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 12 22:03:16 volumio42 volumio[1232]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 22:03:17 volumio42 sudo[7099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 22:02' Dec 12 22:03:17 volumio42 sudo[7099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"