Oct 13 11:00:00 volumio volumio[1245]: info: [2026-10-13T02:00:00.004Z] [JP_Radio] [INFO] [RCT_I012] pgupdate: Updating program listings... Oct 13 11:00:00 volumio volumio[1245]: info: [2026-10-13T02:00:00.049Z] [JP_Radio] [INFO] [PRG_I001] updatePrograms: [cron] 20261013 Oct 13 11:00:36 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:36 volumio volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::ClearQueue Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::stop Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::clearPlayQueue Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:36 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Oct 13 11:00:36 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.305Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.398Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.400Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.401Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.505Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: CBC:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.567Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: TOKAIRADIO:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.568Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: GBS:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.569Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: ZIP-FM:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Oct 13 11:00:36 volumio volumio[1245]: info: CoreCommandRouter::volumioPlay Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::play index 3 Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Oct 13 11:00:36 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.705Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.777Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.778Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.780Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.781Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg Oct 13 11:00:36 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png Oct 13 11:00:36 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png in service jp_radio Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.782Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::stop Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::play index undefined Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::startPlaybackTimer Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:36 volumio volumio[1245]: info: [2026-10-13T02:00:36.860Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:36 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.861Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMAICHI:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.862Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMGIFU:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.862Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: SBS:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.863Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: K-MIX:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.863Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMMIE:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: warn: [2026-10-13T02:00:36.863Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: JOCK:202610131100 cannot find. Oct 13 11:00:36 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:36 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Oct 13 11:00:36 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Oct 13 11:00:37 volumio volumio[1245]: info: sendMpdCommand stop took 417 milliseconds Oct 13 11:00:37 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 11:00:37 volumio volumio[1245]: info: Oct 13 11:00:37 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:37 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:37 volumio volumio[1245]: info: sendMpdCommand clear took 123 milliseconds Oct 13 11:00:37 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg" Oct 13 11:00:37 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:37 volumio volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::ClearQueue Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::stop Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:37 volumio volumio[1245]: info: CorePlayQueue::clearPlayQueue Oct 13 11:00:37 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:37 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Oct 13 11:00:37 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Oct 13 11:00:37 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.663Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.687Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.689Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.691Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:37 volumio volumio[1245]: warn: [2026-10-13T02:00:37.695Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: CBC:202610131100 cannot find. Oct 13 11:00:37 volumio volumio[1245]: warn: [2026-10-13T02:00:37.696Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: TOKAIRADIO:202610131100 cannot find. Oct 13 11:00:37 volumio volumio[1245]: warn: [2026-10-13T02:00:37.796Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: GBS:202610131100 cannot find. Oct 13 11:00:37 volumio volumio[1245]: warn: [2026-10-13T02:00:37.798Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: ZIP-FM:202610131100 cannot find. Oct 13 11:00:37 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:37 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Oct 13 11:00:37 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Oct 13 11:00:37 volumio volumio[1245]: info: CoreCommandRouter::volumioPlay Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::play index 3 Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:37 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Oct 13 11:00:37 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Oct 13 11:00:37 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.904Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.905Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.997Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg Oct 13 11:00:37 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg Oct 13 11:00:37 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg in service jp_radio Oct 13 11:00:37 volumio volumio[1245]: info: [2026-10-13T02:00:37.999Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.000Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.002Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::stop Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::play index undefined Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::startPlaybackTimer Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.087Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:38 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.089Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMAICHI:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.089Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMGIFU:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.090Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: SBS:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.091Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: K-MIX:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.091Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMMIE:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.092Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: JOCK:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Oct 13 11:00:38 volumio volumio[1245]: info: Oct 13 11:00:38 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:38 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:38 volumio volumio[1245]: info: Oct 13 11:00:38 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:38 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:38 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:38 volumio volumio[1245]: info: Oct 13 11:00:38 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:38 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:38 volumio volumio[1245]: info: ------------------------------ 1116ms Oct 13 11:00:38 volumio volumio[1245]: info: sendMpdCommand add "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg" took 1114 milliseconds Oct 13 11:00:38 volumio volumio[1245]: info: sendMpdCommand stop took 431 milliseconds Oct 13 11:00:38 volumio volumio[1245]: info: ------------------------------ 245ms Oct 13 11:00:38 volumio volumio[1245]: info: ------------------------------ 243ms Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 11:00:38 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Oct 13 11:00:38 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 11:00:38 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:38 volumio volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::ClearQueue Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::stop Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::clearPlayQueue Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:38 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Oct 13 11:00:38 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.682Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/CBC/%E3%81%A4%E3%83%9C%E3%82%A4%E3%83%8E%E3%83%AA%E3%82%AA%E3%81%AE%E8%81%9E%E3%81%91%E3%81%B0%E8%81%9E%E3%81%8F%E3%81%BB%E3%81%A9/CBC%E3%83%A9%E3%82%B8%E3%82%AA%2009%3A00-11%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F184e0bc8-5a2a-4e1d-ad89-18f17f9c4a0f.png Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.684Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/TOKAIRADIO//TOKAI%20RADIO%20-/http%3A%2F%2Fradiko.jp%2Fres%2Fbanner%2Fradiko_banner.png Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.685Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/GBS/%EF%BC%AD%EF%BD%95%EF%BD%93%EF%BD%89%EF%BD%83%E3%80%80%EF%BC%B4%EF%BD%95%EF%BD%8E%EF%BD%85/%E3%81%8E%E3%81%B5%E3%83%81%E3%83%A3%E3%83%B3%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F424265da-2b69-4b9f-b133-304c9eae8301.jpeg Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.777Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.780Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: CBC:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.781Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: TOKAIRADIO:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.782Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: GBS:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: warn: [2026-10-13T02:00:38.782Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: ZIP-FM:202610131100 cannot find. Oct 13 11:00:38 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Oct 13 11:00:38 volumio volumio[1245]: info: CoreCommandRouter::volumioPlay Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::play index 3 Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:38 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Oct 13 11:00:38 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Oct 13 11:00:38 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:00:38 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg Oct 13 11:00:38 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg in service jp_radio Oct 13 11:00:38 volumio volumio[1245]: info: [2026-10-13T02:00:38.916Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMAICHI/%E3%81%82%E3%81%90%E3%82%8A%E3%81%9A%E3%82%80/FM%20AICHI%2010%3A50-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F9qwq29dqq7.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg in service jp_radio Oct 13 11:00:39 volumio volumio[1245]: info: [2026-10-13T02:00:39.018Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMGIFU/OH!%20HAPPY%20MORNING/%EF%BC%A6%EF%BC%AD%20%EF%BC%A7%EF%BC%A9%EF%BC%A6%EF%BC%B5%2010%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fa6b0baopq9.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg Oct 13 11:00:39 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg in service jp_radio Oct 13 11:00:39 volumio volumio[1245]: info: [2026-10-13T02:00:39.019Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/SBS/%E9%89%84%E5%B4%8E%E5%B9%B9%E4%BA%BA%E3%81%AEWASABI%20%EF%BC%8810%E6%99%82%E5%8F%B0%EF%BC%89/SBS%E3%83%A9%E3%82%B8%E3%82%AA%2010%3A00-11%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F27d67494-117b-446c-a4e1-9cb47a421714.jpeg Oct 13 11:00:39 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg in service jp_radio Oct 13 11:00:39 volumio volumio[1245]: info: [2026-10-13T02:00:39.021Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/K-MIX/K-MIX%20%E3%83%A2%E3%83%BC%E3%83%8B%E3%83%B3%E3%82%B0%E3%83%A9%E3%82%B8%E3%83%A9/K-MIX%2010%3A00-10%3A52/https%3A%2F%2Fprogram-static.cf.radiko.jp%2F4rid6xgv9p.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg in service jp_radio Oct 13 11:00:39 volumio volumio[1245]: info: [2026-10-13T02:00:39.022Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/FMMIE/OH!%20HAPPY%20MORNING/%E3%83%AC%E3%83%87%E3%82%A3%E3%82%AA%E3%82%AD%E3%83%A5%E3%83%BC%E3%83%96%20%EF%BC%A6%EF%BC%AD%E4%B8%89%E9%87%8D%2009%3A00-10%3A55/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Ftgtlh7qwpo.jpg Oct 13 11:00:39 volumio volumio[1245]: info: Adding Item to queue: http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png Oct 13 11:00:39 volumio volumio[1245]: info: Exploding uri http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png in service jp_radio Oct 13 11:00:39 volumio volumio[1245]: info: [2026-10-13T02:00:39.023Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9001/radiko/play/JOCK/%E5%9B%BD%E4%BC%9A%E4%B8%AD%E7%B6%99%E3%80%8C%E8%A1%86%E8%AD%B0%E9%99%A2%E4%BA%88%E7%AE%97%E5%A7%94%E5%93%A1%E4%BC%9A%E8%B3%AA%E7%96%91%E3%80%8D/NHK%20AM%EF%BC%88%E5%90%8D%E5%8F%A4%E5%B1%8B%EF%BC%89%2008%3A59-11%3A54/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fhmtjkc77xd.png Oct 13 11:00:39 volumio volumio[1245]: info: CoreStateMachine::stop Oct 13 11:00:39 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:39 volumio volumio[1245]: info: CoreStateMachine::play index undefined Oct 13 11:00:39 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 11:00:39 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:39 volumio volumio[1245]: info: CoreStateMachine::startPlaybackTimer Oct 13 11:00:39 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:39 volumio volumio[1245]: info: [2026-10-13T02:00:39.118Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg Oct 13 11:00:39 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 11:00:39 volumio volumio[1245]: warn: [2026-10-13T02:00:39.120Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMAICHI:202610131100 cannot find. Oct 13 11:00:39 volumio volumio[1245]: warn: [2026-10-13T02:00:39.121Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMGIFU:202610131100 cannot find. Oct 13 11:00:39 volumio volumio[1245]: warn: [2026-10-13T02:00:39.122Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: SBS:202610131100 cannot find. Oct 13 11:00:39 volumio volumio[1245]: warn: [2026-10-13T02:00:39.123Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: K-MIX:202610131100 cannot find. Oct 13 11:00:39 volumio volumio[1245]: warn: [2026-10-13T02:00:39.123Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: FMMIE:202610131100 cannot find. Oct 13 11:00:39 volumio volumio[1245]: warn: [2026-10-13T02:00:39.124Z] [JP_Radio] [WARN] [PRG_W001] getCurProgram: JOCK:202610131100 cannot find. Oct 13 11:00:39 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:00:39 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:00:39 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Oct 13 11:00:39 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:39 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:39 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:39 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:39 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:39 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:39 volumio volumio[1245]: info: ------------------------------ 1231ms Oct 13 11:00:39 volumio volumio[1245]: info: sendMpdCommand play took 1125 milliseconds Oct 13 11:00:39 volumio volumio[1245]: info: sendMpdCommand clear took 1125 milliseconds Oct 13 11:00:39 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:39 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:39 volumio volumio[1245]: info: sendMpdCommand stop took 619 milliseconds Oct 13 11:00:39 volumio volumio[1245]: info: ------------------------------ 455ms Oct 13 11:00:39 volumio volumio[1245]: info: ------------------------------ 454ms Oct 13 11:00:39 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg" Oct 13 11:00:39 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:39 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:39 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:39 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:39 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:39 volumio volumio[1245]: info: Oct 13 11:00:39 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:39 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:39 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:40 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:40 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 447ms Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand status took 352 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand add "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg" took 351 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand clear took 350 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 277ms Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand status took 175 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 174ms Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand status took 172 milliseconds Oct 13 11:00:40 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:40 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:40 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:40 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:40 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:40 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:40 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:40 volumio volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 13 11:00:40 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:40 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:40 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:40 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:40 volumio volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 13 11:00:40 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:40 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:40 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:40 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:40 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:40 volumio volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 790ms Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 617ms Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 513ms Oct 13 11:00:40 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 11:00:40 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Oct 13 11:00:40 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg" Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:40 volumio volumio[1245]: error: updateQueue error: null Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 535ms Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 506ms Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand play took 159 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand add "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg" took 160 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 159ms Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 158ms Oct 13 11:00:40 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 11:00:40 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: info: Oct 13 11:00:40 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Oct 13 11:00:40 volumio volumio[1245]: info: Ignoring MPD Status Update Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 339ms Oct 13 11:00:40 volumio volumio[1245]: info: sendMpdCommand play took 333 milliseconds Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 240ms Oct 13 11:00:40 volumio volumio[1245]: info: ------------------------------ 238ms Oct 13 11:00:41 volumio volumio[1245]: info: Oct 13 11:00:41 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:41 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:41 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:41 volumio volumio[1245]: info: Oct 13 11:00:41 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:41 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:41 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:41 volumio volumio[1245]: info: Oct 13 11:00:41 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:42 volumio volumio[1245]: info: Oct 13 11:00:42 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:42 volumio volumio[1245]: error: MPD returned error for command status: Failed to decode "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg"; got HTTP status 404 Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand status took 98 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 11:00:42 volumio volumio[1245]: info: Oct 13 11:00:42 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:42 volumio volumio[1245]: info: Oct 13 11:00:42 volumio volumio[1245]: ---------------------------- MPD announces state update: player Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::getState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Oct 13 11:00:42 volumio volumio[1245]: error: MPD returned error for command status: Failed to decode "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg"; got HTTP status 404 Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand status took 467 milliseconds Oct 13 11:00:42 volumio volumio[1245]: error: MPD returned error for command status: Failed to decode "http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg"; got HTTP status 404 Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand status took 395 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand clearerror took 373 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand status took 306 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 305 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand status took 106 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand status took 104 milliseconds Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","artist":null,"album":null,"uri":"http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","trackType":"jpeg"} Oct 13 11:00:42 volumio volumio[1245]: verbose: CURRENT POSITION 3 Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: No code Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.596+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.622+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.622+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio[1245]: info: ------------------------------ 703ms Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand clearerror took 308 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand clearerror took 306 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 245 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 235 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 234 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 211 milliseconds Oct 13 11:00:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 217 milliseconds Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Oct 13 11:00:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","artist":null,"album":null,"uri":"http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","trackType":"jpeg"} Oct 13 11:00:42 volumio volumio[1245]: verbose: CURRENT POSITION 3 Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: No code Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","artist":null,"album":null,"uri":"http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","trackType":"jpeg"} Oct 13 11:00:42 volumio volumio[1245]: verbose: CURRENT POSITION 3 Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: No code Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","artist":null,"album":null,"uri":"http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","trackType":"jpeg"} Oct 13 11:00:42 volumio volumio[1245]: verbose: CURRENT POSITION 3 Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: No code Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","artist":null,"album":null,"uri":"http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","trackType":"jpeg"} Oct 13 11:00:42 volumio volumio[1245]: verbose: CURRENT POSITION 3 Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: No code Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: ControllerMpd::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:00:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","artist":null,"album":null,"uri":"http://localhost:9001/radiko/play/ZIP-FM/BRUNCH%20STYLE/ZIP-FM%2009%3A00-12%3A00/https%3A%2F%2Fprogram-static.cf.radiko.jp%2Fc4c67ec3-2a5d-4445-abb8-d48166c5e902.jpeg","trackType":"jpeg"} Oct 13 11:00:42 volumio volumio[1245]: verbose: CURRENT POSITION 3 Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio[1245]: info: No code Oct 13 11:00:42 volumio volumio[1245]: info: CoreStateMachine::pushState Oct 13 11:00:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.907+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.909+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.917+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.925+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.934+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.941+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.944+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.948+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.954+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.958+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.965+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.969+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.975+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.984+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:42 volumio volumio5-onboarding[1680]: time=2026-10-13T11:00:42.989+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 11:00:43 volumio volumio[1245]: info: ------------------------------ 1258ms Oct 13 11:00:43 volumio volumio[1245]: info: ------------------------------ 1258ms Oct 13 11:00:43 volumio volumio[1245]: info: ------------------------------ 1259ms Oct 13 11:00:43 volumio volumio[1245]: info: ------------------------------ 901ms Oct 13 11:00:43 volumio volumio[1245]: info: ------------------------------ 901ms Oct 13 11:00:53 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 11:00:53 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 11:00:53 volumio nmbd[1127]: [2026/10/13 11:00:53.733749, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 13 11:00:53 volumio nmbd[1127]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.21 for name WORKGROUP<1d>. Oct 13 11:00:53 volumio nmbd[1127]: This response was from IP 192.168.3.2, reporting an IP address of 192.168.3.2. Oct 13 11:00:54 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 11:00:54 volumio volumio[1245]: info: [2026-10-13T02:00:54.543Z] [JP_Radio] [INFO] [IDX_I005] getUIConfig: Oct 13 11:00:57 volumio kernel: EXT4-fs (mmcblk0p3): error count since last fsck: 2 Oct 13 11:00:57 volumio kernel: EXT4-fs (mmcblk0p3): initial error at time 1780403966: __ext4_find_entry:1656: inode 4512558 Oct 13 11:00:57 volumio kernel: EXT4-fs (mmcblk0p3): last error at time 1780403966: __ext4_find_entry:1656: inode 4512558 Oct 13 11:00:58 volumio volumio[1245]: info: [2026-10-13T02:00:58.519Z] [JP_Radio] [INFO] [RCT_I012] pgupdate: Updating program listings... Oct 13 11:00:58 volumio volumio[1245]: info: [2026-10-13T02:00:58.528Z] [JP_Radio] [INFO] [PRG_I001] updatePrograms: [boot] 20261013 Oct 13 11:00:59 volumio volumio[1245]: info: CALLMETHOD: music_service jp_radio saveNetworkSetting [object Object] Oct 13 11:00:59 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: jp_radio , saveNetworkSetting Oct 13 11:00:59 volumio volumio[1245]: info: [2026-10-13T02:00:59.941Z] [JP_Radio] [INFO] [IDX_I028] saveNetworkSetting Oct 13 11:01:02 volumio volumio[1245]: info: CALLMETHOD: music_service jp_radio restartPlugin [object Object] Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: jp_radio , restartPlugin Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.490Z] [JP_Radio] [INFO] [IDX_I027] restartPlugin Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.491Z] [JP_Radio] [INFO] [IDX_I004] onStop: Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.492Z] [JP_Radio] [INFO] [IDX_I026] removeOwnQueueItems: removed 10 item(s) Oct 13 11:01:02 volumio volumio[1245]: info: CorePlayQueue::saveQueue Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.503Z] [JP_Radio] [INFO] [PRG_I002] DB compacting Oct 13 11:01:02 volumio volumio[1245]: (node:1245) DeprecationWarning: @seald-io/nedb: calling Datastore#persistence#compactDatafile is deprecated, please use Datastore#compactDatafile, it will be removed in the next major version. Oct 13 11:01:02 volumio volumio[1245]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRADIKO Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 11:01:02 volumio volumio[1245]: Cannot find translation for source Podcast Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.527Z] [JP_Radio] [INFO] [IDX_I001] onStart: START Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.547Z] [JP_Radio] [INFO] [RCT_I001] setupRoutes Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.550Z] [JP_Radio] [INFO] [RCT_I008] start Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.553Z] [JP_Radio] [INFO] [RCT_I011] init Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.553Z] [JP_Radio] [INFO] [RDK_I001] Attempting login Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.553Z] [JP_Radio] [INFO] [RDK_I003] checkLogin Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.558Z] [JP_Radio] [INFO] [IDX_I003] onStart: EXIT Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.567Z] [JP_Radio] [INFO] [RCT_I010] Listening on port 9000 Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Oct 13 11:01:02 volumio volumio[1245]: info: CorePlayQueue::getTrack 3 Oct 13 11:01:02 volumio volumio[1245]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received undefined Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.576Z] [JP_Radio] [INFO] [IDX_I006] addToBrowseSources: pluginName=jp_radio Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 11:01:02 volumio volumio[1245]: info: [1791856862577] CoreMusicLibrary::Adding element RADIKO Oct 13 11:01:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 11:01:02 volumio volumio[1245]: Cannot find translation for source Podcast Oct 13 11:01:02 volumio volumio[1245]: Cannot find translation for source RADIKO Oct 13 11:01:02 volumio volumio[1245]: info: [2026-10-13T02:01:02.586Z] [JP_Radio] [INFO] [IDX_I002] onStart: COMPLETE Oct 13 11:01:05 volumio volumio[1245]: info: [2026-10-13T02:01:05.209Z] [JP_Radio] [INFO] [RDK_I005] premium not logged in (HTTP 400) Oct 13 11:01:05 volumio volumio[1245]: info: [2026-10-13T02:01:05.209Z] [JP_Radio] [INFO] [RDK_I002] login Oct 13 11:01:06 volumio volumio[1245]: info: [2026-10-13T02:01:06.293Z] [JP_Radio] [INFO] [RDK_I003] checkLogin Oct 13 11:01:06 volumio volumio[1245]: info: [2026-10-13T02:01:06.674Z] [JP_Radio] [INFO] [RDK_I004] Login status: AreaFree Oct 13 11:01:06 volumio volumio[1245]: info: [2026-10-13T02:01:06.675Z] [JP_Radio] [INFO] [RDK_I006] getToken Oct 13 11:01:06 volumio volumio[1245]: info: [2026-10-13T02:01:06.675Z] [JP_Radio] [INFO] [RDK_I008] auth1 Oct 13 11:01:06 volumio volumio[1245]: info: [2026-10-13T02:01:06.872Z] [JP_Radio] [INFO] [RDK_I009] getPartialKey Oct 13 11:01:06 volumio volumio[1245]: info: [2026-10-13T02:01:06.873Z] [JP_Radio] [INFO] [RDK_I010] auth2 Oct 13 11:01:07 volumio volumio[1245]: info: [2026-10-13T02:01:07.279Z] [JP_Radio] [INFO] [RDK_I007] getToken: areaId=JP13 Oct 13 11:01:07 volumio volumio[1245]: info: [2026-10-13T02:01:07.279Z] [JP_Radio] [INFO] [RDK_I011] getStations Oct 13 11:01:07 volumio kernel: hwmon hwmon1: Voltage normalised Oct 13 11:01:08 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 11:01:08 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 11:01:11 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 13 11:01:13 volumio volumio[1245]: info: [2026-10-13T02:01:13.378Z] [JP_Radio] [INFO] [RCT_I012] pgupdate: Updating program listings... Oct 13 11:01:13 volumio volumio[1245]: info: [2026-10-13T02:01:13.387Z] [JP_Radio] [INFO] [PRG_I001] updatePrograms: [boot] 20261013 Oct 13 11:01:15 volumio volumio[1245]: TypeError: Cannot read properties of null (reading 'clearOldProgram') Oct 13 11:01:15 volumio volumio[1245]: at #pgupdate (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:1102:28) Oct 13 11:01:15 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 13 11:01:15 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Oct 13 11:01:15 volumio volumio[1245]: info: [2026-10-13T02:01:15.705Z] [JP_Radio] [INFO] [IDX_I025] handleBrowseUri: curUri=radiko Oct 13 11:01:16 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:17 volumio kernel: hwmon hwmon1: Voltage normalised Oct 13 11:01:26 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Oct 13 11:01:26 volumio volumio[1245]: info: [2026-10-13T02:01:26.492Z] [JP_Radio] [INFO] [IDX_I025] handleBrowseUri: curUri=radiko/timefree Oct 13 11:01:26 volumio volumio[1245]: info: [2026-10-13T02:01:26.492Z] [JP_Radio] [INFO] [RCT_I006] timeFreeStations Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:26 volumio volumio[1245]: info: Preload queue cleared Oct 13 11:01:27 volumio volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 11:01:27 volumio volumio[1245]: TypeError: Cannot read properties of null (reading 'clearOldProgram') Oct 13 11:01:27 volumio volumio[1245]: at #pgupdate (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:1102:28) Oct 13 11:01:27 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 13 11:01:27 volumio volumio[1245]: at async #init (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:1076:9) Oct 13 11:01:27 volumio volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 11:01:27 volumio sudo[4237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 11:00' Oct 13 11:01:27 volumio sudo[4237]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"