-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Thu 2025-06-19 11:06:54 JST. -- Jun 19 11:05:15 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Jun 19 11:05:15 volumio volumio[9644]: info: JP_Radio::JpRadio.radioStations Jun 19 11:05:16 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:05:16 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:05:16 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:05:16 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/JOFK/%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A00-11%3A05/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fd3e92ac3-843e-4309-8ddc-69879f352ffd.png Jun 19 11:05:16 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:05:16 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/RN1/%E3%83%9E%E3%83%BC%E3%82%B1%E3%83%83%E3%83%88%E3%83%97%E3%83%AC%E3%82%B9%E3%80%80%E5%89%8D%E5%A0%B4/%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC1%2009%3A00-11%3A35/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fj1ukn0x1xa.png Jun 19 11:05:16 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/RN2/RaNi%20Music%E2%99%AAMorning/%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC2%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F88a8ec34-ef4d-471f-a21f-cbc6e63d48f9.png Jun 19 11:05:16 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/JOAK-FM/%EF%BC%A6%EF%BC%AD%E8%83%BD%E6%A5%BD%E5%A0%82%E3%80%80%E8%A6%B3%E4%B8%96%E6%B5%81%E3%80%8C%E9%A4%8A%E8%80%81%E3%80%8D%E3%80%8C%E7%8E%89%E9%AC%98%EF%BC%88%E4%B8%80%E9%83%A8%EF%BC%89%E3%80%8D/NHK-FM%EF%BC%88%E6%9D%B1%E4%BA%AC%EF%BC%89%2011%3A00-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fm426o1ptwo.jpg Jun 19 11:05:16 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/RN1/%E3%83%9E%E3%83%BC%E3%82%B1%E3%83%83%E3%83%88%E3%83%97%E3%83%AC%E3%82%B9%E3%80%80%E5%89%8D%E5%A0%B4/%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC1%2009%3A00-11%3A35/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fj1ukn0x1xa.png in service jp_radio Jun 19 11:05:16 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/RN1/%E3%83%9E%E3%83%BC%E3%82%B1%E3%83%83%E3%83%88%E3%83%97%E3%83%AC%E3%82%B9%E3%80%80%E5%89%8D%E5%A0%B4/%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC1%2009%3A00-11%3A35/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fj1ukn0x1xa.png Jun 19 11:05:16 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/RN2/RaNi%20Music%E2%99%AAMorning/%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC2%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F88a8ec34-ef4d-471f-a21f-cbc6e63d48f9.png in service jp_radio Jun 19 11:05:16 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/RN2/RaNi%20Music%E2%99%AAMorning/%E3%83%A9%E3%82%B8%E3%82%AANIKKEI%E7%AC%AC2%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F88a8ec34-ef4d-471f-a21f-cbc6e63d48f9.png Jun 19 11:05:16 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/JOAK-FM/%EF%BC%A6%EF%BC%AD%E8%83%BD%E6%A5%BD%E5%A0%82%E3%80%80%E8%A6%B3%E4%B8%96%E6%B5%81%E3%80%8C%E9%A4%8A%E8%80%81%E3%80%8D%E3%80%8C%E7%8E%89%E9%AC%98%EF%BC%88%E4%B8%80%E9%83%A8%EF%BC%89%E3%80%8D/NHK-FM%EF%BC%88%E6%9D%B1%E4%BA%AC%EF%BC%89%2011%3A00-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fm426o1ptwo.jpg in service jp_radio Jun 19 11:05:16 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/JOAK-FM/%EF%BC%A6%EF%BC%AD%E8%83%BD%E6%A5%BD%E5%A0%82%E3%80%80%E8%A6%B3%E4%B8%96%E6%B5%81%E3%80%8C%E9%A4%8A%E8%80%81%E3%80%8D%E3%80%8C%E7%8E%89%E9%AC%98%EF%BC%88%E4%B8%80%E9%83%A8%EF%BC%89%E3%80%8D/NHK-FM%EF%BC%88%E6%9D%B1%E4%BA%AC%EF%BC%89%2011%3A00-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fm426o1ptwo.jpg Jun 19 11:05:17 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::ClearQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::stop Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::clearPlayQueue Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::addQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::addQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:05:17 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:05:17 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg in service jp_radio Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPlay Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::play index 0 Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::addQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::addQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:05:17 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:05:17 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg in service jp_radio Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:05:17 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/JOFK/%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A00-11%3A05/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fd3e92ac3-843e-4309-8ddc-69879f352ffd.png Jun 19 11:05:17 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/JOFK/%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A00-11%3A05/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fd3e92ac3-843e-4309-8ddc-69879f352ffd.png in service jp_radio Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/JOFK/%E3%83%8B%E3%83%A5%E3%83%BC%E3%82%B9/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A00-11%3A05/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fd3e92ac3-843e-4309-8ddc-69879f352ffd.png Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::stop Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::play index undefined Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::startPlaybackTimer Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::clearAddPlayTrack: track=service,jp_radio,type,song,title,テレフォン人生相談,name,テレフォン人生相談,artist,ＫＲＹ山口放送 11:00-11:20,albumart,https://program-static.cf.radiko.jp/6c32esrdbq.jpg,uri,http://localhost:9000/radiko/play/KRY,samplerate,,bitdepth,0,channels,0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: //--[ #1 ]-------------------------- Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: arrayQueue[0]=service,jp_radio,type,song,title,テレフォン人生相談,name,テレフォン人生相談,artist,ＫＲＹ山口放送 11:00-11:20,albumart,https://program-static.cf.radiko.jp/6c32esrdbq.jpg,uri,http://localhost:9000/radiko/play/KRY,samplerate,,bitdepth,0,channels,0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: ----[ #1 ]--------------------------// Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:05:17 volumio volumio[9644]: info: sendMpdCommand stop took 17 milliseconds Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: //--[ #2 ]-------------------------- Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: arrayQueue[0]=service,jp_radio,type,song,title,テレフォン人生相談,name,テレフォン人生相談,artist,ＫＲＹ山口放送 11:00-11:20,albumart,https://program-static.cf.radiko.jp/6c32esrdbq.jpg,uri,http://localhost:9000/radiko/play/KRY,samplerate,,bitdepth,0,channels,0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: arrayQueue[1]=service,jp_radio,type,song,title,ディア・フレンズ,name,ディア・フレンズ,artist,エフエム山口 11:00-11:30,albumart,https://program-static.cf.radiko.jp/jwgoow1sxa.jpg,uri,http://localhost:9000/radiko/play/FMY,samplerate,,bitdepth,0,channels,0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: arrayQueue[2]=service,jp_radio,type,song,title,ニュース,name,ニュース,artist,NHKラジオ第1（広島） 11:00-11:05,albumart,https://program-static.cf.radiko.jp/d3e92ac3-843e-4309-8ddc-69879f352ffd.png,uri,http://localhost:9000/radiko/play/JOFK,samplerate,,bitdepth,0,channels,0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: ----[ #2 ]--------------------------// Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioClearQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::ClearQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::stop Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::clearPlayQueue Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:05:17 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 11:05:17 volumio volumio[9644]: info: Jun 19 11:05:17 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:05:17 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:05:17 volumio volumio[9644]: info: sendMpdCommand clear took 22 milliseconds Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: //--[ #3 ]-------------------------- Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: ----[ #3 ]--------------------------// Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioAddQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::addQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::addQueueItems Jun 19 11:05:17 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:05:17 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:05:17 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg in service jp_radio Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: verbose: STATE SERVICE {"status":"stop","position":0,"title":"テレフォン人生相談","artist":"ＫＲＹ山口放送 11:00-11:20","albumart":"https://program-static.cf.radiko.jp/6c32esrdbq.jpg","uri":"http://localhost:9000/radiko/play/KRY","seek":0,"samplerate":"","bitdepth":0,"channels":0,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"updatedb":false,"volatile":false,"service":"jp_radio"} Jun 19 11:05:17 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::syncState stateService stop Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus stop Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:17 volumio volumio[9644]: info: No code Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:17 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/KRY" Jun 19 11:05:17 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:05:17 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:05:17 volumio volumio[9644]: info: Jun 19 11:05:17 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:05:17 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:05:17 volumio volumio[9644]: info: Jun 19 11:05:17 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:05:17 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:05:17 volumio volumio[9644]: error: updateQueue error: null Jun 19 11:05:17 volumio volumio[9644]: info: Jun 19 11:05:17 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:05:17 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:05:17 volumio volumio[9644]: info: ------------------------------ 121ms Jun 19 11:05:17 volumio volumio[9644]: info: sendMpdCommand add "http://localhost:9000/radiko/play/KRY" took 86 milliseconds Jun 19 11:05:17 volumio volumio[9644]: info: ------------------------------ 20ms Jun 19 11:05:17 volumio volumio[9644]: info: ------------------------------ 16ms Jun 19 11:05:17 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: //--[ #4 ]-------------------------- Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: arrayQueue[0]=service,jp_radio,type,song,title,テレフォン人生相談,name,テレフォン人生相談,artist,ＫＲＹ山口放送 11:00-11:20,albumart,https://program-static.cf.radiko.jp/6c32esrdbq.jpg,uri,http://localhost:9000/radiko/play/KRY,samplerate,,bitdepth,0,channels,0 Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio:: ----[ #4 ]--------------------------// Jun 19 11:05:17 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand play Jun 19 11:05:17 volumio volumio[9644]: info: Jun 19 11:05:17 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:05:17 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:05:17 volumio volumio[9644]: info: Jun 19 11:05:17 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:05:17 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:05:17 volumio volumio[9644]: info: ------------------------------ 16ms Jun 19 11:05:17 volumio volumio[9644]: info: sendMpdCommand play took 10 milliseconds Jun 19 11:05:17 volumio volumio[9644]: info: ------------------------------ 8ms Jun 19 11:05:17 volumio volumio[9644]: info: ------------------------------ 5ms Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::JpRadio.#setupRoutes.get=> req.originalUrl=/radiko/play/KRY Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream Jun 19 11:05:17 volumio volumio[9644]: info: JP_Radio::Radiko.play station=>KRY Jun 19 11:05:18 volumio volumio[9644]: info: JP_Radio::Radiko.play: ffmpeg -y,-headers,X-Radiko-Authtoken:2eWT7lY7zLzOqcvByMRsQA,-i,https://f-radiko.smartstream.ne.jp/KRY/_definst_/simul-stream.stream/chunklist_w120239253.m3u8,-acodec,copy,-f,adts,-loglevel,error,pipe:1 Jun 19 11:05:18 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream: ffmpeg=10547 Jun 19 11:05:18 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream: Streaming started Jun 19 11:05:19 volumio volumio[9644]: info: Jun 19 11:05:19 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:05:19 volumio volumio[9644]: info: Jun 19 11:05:19 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:05:19 volumio volumio[9644]: info: Jun 19 11:05:19 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand status took 25 milliseconds Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:05:19 volumio volumio[9644]: info: Jun 19 11:05:19 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand status took 29 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand status took 25 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: 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":"51 Kbps","isStreaming":false,"title":"KRY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/KRY","trackType":""} Jun 19 11:05:19 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus stop Jun 19 11:05:19 volumio volumio[9644]: info: ------------------------------ 51ms Jun 19 11:05:19 volumio volumio[9644]: info: Jun 19 11:05:19 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:05:19 volumio volumio[9644]: info: Jun 19 11:05:19 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand status took 24 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand status took 8 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand status took 6 milliseconds Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"51 Kbps","isStreaming":false,"title":"KRY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/KRY","trackType":""} Jun 19 11:05:19 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:05:19 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"51 Kbps","isStreaming":false,"title":"KRY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/KRY","trackType":""} Jun 19 11:05:19 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:05:19 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: ------------------------------ 156ms Jun 19 11:05:19 volumio volumio[9644]: info: ------------------------------ 154ms Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 105 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 102 milliseconds Jun 19 11:05:19 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 102 milliseconds Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:05:19 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"51 Kbps","isStreaming":false,"title":"KRY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/KRY","trackType":""} Jun 19 11:05:19 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:05:19 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"51 Kbps","isStreaming":false,"title":"KRY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/KRY","trackType":""} Jun 19 11:05:19 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:05:19 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"51 Kbps","isStreaming":false,"title":"KRY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/KRY","trackType":""} Jun 19 11:05:19 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:05:19 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:19 volumio volumio[9644]: info: ------------------------------ 276ms Jun 19 11:05:19 volumio volumio[9644]: info: ------------------------------ 260ms Jun 19 11:05:19 volumio volumio[9644]: info: ------------------------------ 258ms Jun 19 11:05:19 volumio volumio[9644]: info: CoreCommandRouter::volumioGetState Jun 19 11:05:21 volumio volumio[9644]: info: JP_Radio::JpRadio.#pushSongState: pos=0, "ＫＲＹ山口放送 / 11:00-11:20", now=11:05:01 Jun 19 11:05:21 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:05:21 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:05:21 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"title":"テレフォン人生相談","artist":"ＫＲＹ山口放送 / 11:00-11:20","album":null,"albumart":"https://program-static.cf.radiko.jp/6c32esrdbq.jpg","uri":"http://localhost:9000/radiko/play/KRY","trackType":"webradio","seek":301000,"duration":1200,"samplerate":"","bitdepth":"","channels":2,"bitrate":"51 Kbps","repeat":false,"repeatSingle":false,"consume":true,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Jun 19 11:05:21 volumio volumio[9644]: verbose: CURRENT POSITION 0 Jun 19 11:05:21 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:05:21 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:05:21 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:05:21 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:21 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 11:05:21 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:21 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:05:21 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:05:28 volumio volumio[9644]: info: CALLMETHOD: system_controller system enableLiveLog false Jun 19 11:05:28 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Jun 19 11:05:28 volumio volumio[9644]: info: Launching a new LiveLog session Jun 19 11:05:28 volumio volumio[9644]: info: Live Log process terminated: null Jun 19 11:06:42 volumio volumio[9644]: info: CALLMETHOD: system_controller system enableLiveLog true Jun 19 11:06:42 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Jun 19 11:06:42 volumio volumio[9644]: info: Launching a new LiveLog session Jun 19 11:06:47 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Jun 19 11:06:47 volumio volumio[9644]: info: JP_Radio::JpRadio.radioStations Jun 19 11:06:47 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:06:47 volumio volumio[9644]: info: Preloading song: http://localhost:9000/radiko/play/JOFK/%E3%81%B5%E3%82%93%E3%82%8F%E3%82%8A%E3%80%80%E3%81%BE%E3%81%84%E3%81%AB%E3%81%A1%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%E2%99%AA%E3%80%80%E6%9C%A8%E6%9B%9C%EF%BC%91%EF%BC%91%E6%99%82%E5%8F%B0%EF%BC%88%EF%BC%96%EF%BC%8F%EF%BC%91%EF%BC%99%EF%BC%89/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A05-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fka33z2p12f.jpg Jun 19 11:06:47 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:06:51 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::ClearQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::stPlaybackTimer Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::serviceStop Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 0 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::serviceStop Jun 19 11:06:51 volumio volumio[9644]: info: [2025-06-19T02:06:51.327Z] JP_Radio::stop Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::clearPlayQueue Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::addQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::addQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:06:51 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:06:51 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg in service jp_radio Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/KRY/%E3%83%86%E3%83%AC%E3%83%95%E3%82%A9%E3%83%B3%E4%BA%BA%E7%94%9F%E7%9B%B8%E8%AB%87/%EF%BC%AB%EF%BC%B2%EF%BC%B9%E5%B1%B1%E5%8F%A3%E6%94%BE%E9%80%81%2011%3A00-11%3A20/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F6c32esrdbq.jpg Jun 19 11:06:51 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:06:51 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg in service jp_radio Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPlay Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::play index 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::addQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::addQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:06:51 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/JOFK/%E3%81%B5%E3%82%93%E3%82%8F%E3%82%8A%E3%80%80%E3%81%BE%E3%81%84%E3%81%AB%E3%81%A1%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%E2%99%AA%E3%80%80%E6%9C%A8%E6%9B%9C%EF%BC%91%EF%BC%91%E6%99%82%E5%8F%B0%EF%BC%88%EF%BC%96%EF%BC%8F%EF%BC%91%EF%BC%99%EF%BC%89/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A05-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fka33z2p12f.jpg Jun 19 11:06:51 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/JOFK/%E3%81%B5%E3%82%93%E3%82%8F%E3%82%8A%E3%80%80%E3%81%BE%E3%81%84%E3%81%AB%E3%81%A1%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%E2%99%AA%E3%80%80%E6%9C%A8%E6%9B%9C%EF%BC%91%EF%BC%91%E6%99%82%E5%8F%B0%EF%BC%88%EF%BC%96%EF%BC%8F%EF%BC%91%EF%BC%99%EF%BC%89/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A05-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fka33z2p12f.jpg in service jp_radio Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/JOFK/%E3%81%B5%E3%82%93%E3%82%8F%E3%82%8A%E3%80%80%E3%81%BE%E3%81%84%E3%81%AB%E3%81%A1%E3%83%AA%E3%82%AF%E3%82%A8%E3%82%B9%E3%83%88%E2%99%AA%E3%80%80%E6%9C%A8%E6%9B%9C%EF%BC%91%EF%BC%91%E6%99%82%E5%8F%B0%EF%BC%88%EF%BC%96%EF%BC%8F%EF%BC%91%EF%BC%99%EF%BC%89/NHK%E3%83%A9%E3%82%B8%E3%82%AA%E7%AC%AC1%EF%BC%88%E5%BA%83%E5%B3%B6%EF%BC%89%2011%3A05-11%3A50/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fka33z2p12f.jpg Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::play index undefined Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::startPlaybackTimer Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::clearAddPlayTrack: track=service,jp_radio,type,song,title,ディア・フレンズ,name,ディア・フレンズ,artist,エフエム山口 11:00-11:30,albumart,https://program-static.cf.radiko.jp/jwgoow1sxa.jpg,uri,http://localhost:9000/radiko/play/FMY,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: //--[ #1 ]-------------------------- Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: arrayQueue[0]=service,jp_radio,type,song,title,テレフォン人生相談,name,テレフォン人生相談,artist,ＫＲＹ山口放送 11:00-11:20,albumart,https://program-static.cf.radiko.jp/6c32esrdbq.jpg,uri,http://localhost:9000/radiko/play/KRY,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: arrayQueue[1]=service,jp_radio,type,song,title,ディア・フレンズ,name,ディア・フレンズ,artist,エフエム山口 11:00-11:30,albumart,https://program-static.cf.radiko.jp/jwgoow1sxa.jpg,uri,http://localhost:9000/radiko/play/FMY,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: ----[ #1 ]--------------------------// Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand stop took 75 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand stop took 33 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: //--[ #2 ]-------------------------- Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: arrayQueue[0]=service,jp_radio,type,song,title,テレフォン人生相談,name,テレフォン人生相談,artist,ＫＲＹ山口放送 11:00-11:20,albumart,https://program-static.cf.radiko.jp/6c32esrdbq.jpg,uri,http://localhost:9000/radiko/play/KRY,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: arrayQueue[1]=service,jp_radio,type,song,title,ディア・フレンズ,name,ディア・フレンズ,artist,エフエム山口 11:00-11:30,albumart,https://program-static.cf.radiko.jp/jwgoow1sxa.jpg,uri,http://localhost:9000/radiko/play/FMY,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: arrayQueue[2]=service,jp_radio,type,song,title,ふんわり　まいにちリクエスト♪　木曜１１時台（６／１９）,name,ふんわり　まいにちリクエスト♪　木曜１１時台（６／１９）,artist,NHKラジオ第1（広島） 11:05-11:50,albumart,https://program-static.cf.radiko.jp/ka33z2p12f.jpg,uri,http://localhost:9000/radiko/play/JOFK,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: ----[ #2 ]--------------------------// Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioClearQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::ClearQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::clearPlayQueue Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:51 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:51 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:06:51 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand status took 103 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand clear took 76 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand status took 47 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand status took 43 milliseconds Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: //--[ #3 ]-------------------------- Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: ----[ #3 ]--------------------------// Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioAddQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::addQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::addQueueItems Jun 19 11:06:51 volumio volumio[9644]: info: Preload queue cleared Jun 19 11:06:51 volumio volumio[9644]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:06:51 volumio volumio[9644]: info: Exploding uri http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg in service jp_radio Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::explodeUri: uri=http://localhost:9000/radiko/play/FMY/%E3%83%87%E3%82%A3%E3%82%A2%E3%83%BB%E3%83%95%E3%83%AC%E3%83%B3%E3%82%BA/%E3%82%A8%E3%83%95%E3%82%A8%E3%83%A0%E5%B1%B1%E5%8F%A3%2011%3A00-11%3A30/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fjwgoow1sxa.jpg Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: verbose: STATE SERVICE {"status":"stop","position":0,"title":"ディア・フレンズ","artist":"エフエム山口 11:00-11:30","albumart":"https://program-static.cf.radiko.jp/jwgoow1sxa.jpg","uri":"http://localhost:9000/radiko/play/FMY","seek":0,"samplerate":"","bitdepth":0,"channels":0,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"updatedb":false,"volatile":false,"service":"jp_radio"} Jun 19 11:06:51 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::syncState stateService stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: No code Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/FMY" Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:51 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 19 11:06:51 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::syncState stateService stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: No code Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 19 11:06:51 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::syncState stateService stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus stop Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: No code Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushState Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 254ms Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 252ms Jun 19 11:06:51 volumio volumio[9644]: info: CoreCommandRouter::volumioPushQueue Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::saveQueue Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::updateTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: CorePlayQueue::getTrackBlock Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:06:51 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:06:51 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream: res.on(close) Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream: SIGTERM sent to ffmpeg group 10547 Jun 19 11:06:51 volumio volumio[9644]: error: updateQueue error: null Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:06:51 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 252ms Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 246 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand add "http://localhost:9000/radiko/play/FMY" took 218 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 27ms Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 27ms Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:51 volumio volumio[9644]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: //--[ #4 ]-------------------------- Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: arrayQueue[0]=service,jp_radio,type,song,title,ディア・フレンズ,name,ディア・フレンズ,artist,エフエム山口 11:00-11:30,albumart,https://program-static.cf.radiko.jp/jwgoow1sxa.jpg,uri,http://localhost:9000/radiko/play/FMY,samplerate,,bitdepth,0,channels,0 Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio:: ----[ #4 ]--------------------------// Jun 19 11:06:51 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand play Jun 19 11:06:51 volumio volumio[9644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 362ms Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:06:51 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:06:51 volumio volumio[9644]: info: Jun 19 11:06:51 volumio volumio[9644]: ---------------------------- MPD announces system playlist update Jun 19 11:06:51 volumio volumio[9644]: info: Ignoring MPD Status Update Jun 19 11:06:51 volumio volumio[9644]: info: touch_display: Setting screensaver timeout to 600 seconds. Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 37ms Jun 19 11:06:51 volumio volumio[9644]: info: sendMpdCommand play took 30 milliseconds Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 28ms Jun 19 11:06:51 volumio volumio[9644]: info: ------------------------------ 26ms Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::JpRadio.#setupRoutes.get=> req.originalUrl=/radiko/play/FMY Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::Radiko.play station=>FMY Jun 19 11:06:51 volumio volumio[9644]: info: JP_Radio::Radiko.play: ffmpeg -y,-headers,X-Radiko-Authtoken:2eWT7lY7zLzOqcvByMRsQA,-i,https://f-radiko.smartstream.ne.jp/FMY/_definst_/simul-stream.stream/chunklist_w2036135266.m3u8,-acodec,copy,-f,adts,-loglevel,error,pipe:1 Jun 19 11:06:52 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream: ffmpeg=10800 Jun 19 11:06:52 volumio volumio[9644]: info: JP_Radio::JpRadio.#startStream: Streaming started Jun 19 11:06:53 volumio volumio[9644]: info: Jun 19 11:06:53 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:53 volumio volumio[9644]: info: Jun 19 11:06:53 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:53 volumio volumio[9644]: info: Jun 19 11:06:53 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand status took 25 milliseconds Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:53 volumio volumio[9644]: info: Jun 19 11:06:53 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand status took 29 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand status took 27 milliseconds Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:53 volumio volumio[9644]: info: Jun 19 11:06:53 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:53 volumio volumio[9644]: info: Jun 19 11:06:53 volumio volumio[9644]: ---------------------------- MPD announces state update: player Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::getState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand status Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 17 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand status took 13 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand status took 8 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand status took 5 milliseconds Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseState Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: 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":"42 Kbps","isStreaming":false,"title":"FMY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMY","trackType":""} Jun 19 11:06:53 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus stop Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"FMY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMY","trackType":""} Jun 19 11:06:53 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:06:53 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":315,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"FMY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMY","trackType":""} Jun 19 11:06:53 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:06:53 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: info: ------------------------------ 77ms Jun 19 11:06:53 volumio volumio[9644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 19 11:06:53 volumio volumio[9644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 19 11:06:53 volumio volumio[9644]: info: ------------------------------ 77ms Jun 19 11:06:53 volumio volumio[9644]: info: ------------------------------ 75ms Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 19 11:06:53 volumio volumio[9644]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:53 volumio volumio[9644]: verbose: ControllerMpd::parseTrackInfo Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"FMY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMY","trackType":""} Jun 19 11:06:53 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:06:53 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"FMY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMY","trackType":""} Jun 19 11:06:53 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:06:53 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: info: ControllerMpd::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CoreCommandRouter::servicePushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"FMY","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMY","trackType":""} Jun 19 11:06:53 volumio volumio[9644]: verbose: CURRENT POSITION 1 Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState stateService play Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::syncState currentStatus play Jun 19 11:06:53 volumio volumio[9644]: info: Received an update from plugin. extracting info from payload Jun 19 11:06:53 volumio volumio[9644]: info: CoreStateMachine::pushState Jun 19 11:06:53 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:53 volumio volumio[9644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 19 11:06:53 volumio volumio[9644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 19 11:06:53 volumio volumio[9644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 19 11:06:53 volumio volumio[9644]: info: ------------------------------ 75ms Jun 19 11:06:53 volumio volumio[9644]: info: ------------------------------ 68ms Jun 19 11:06:53 volumio volumio[9644]: info: ------------------------------ 64ms Jun 19 11:06:54 volumio volumio[9644]: info: CoreCommandRouter::volumioGetState Jun 19 11:06:54 volumio volumio[9644]: info: CorePlayQueue::getTrack 1 Jun 19 11:06:54 volumio volumio[9644]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 11:06:54 volumio volumio[9644]: TypeError: Cannot read property 'name' of undefined Jun 19 11:06:54 volumio volumio[9644]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 19 11:06:54 volumio volumio[9644]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 19 11:06:54 volumio volumio[9644]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Jun 19 11:06:54 volumio volumio[9644]: at Socket.emit (events.js:315:20) Jun 19 11:06:54 volumio volumio[9644]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 19 11:06:54 volumio volumio[9644]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 19 11:06:54 volumio volumio[9644]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 11:06:54 volumio sudo[10810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 11:05 Jun 19 11:06:54 volumio sudo[10810]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"