Oct 12 08:54:00 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:05 technics-pi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8237. Oct 12 08:54:05 technics-pi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:05 technics-pi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:05 technics-pi5 upmpdcli[10123]: Could not open config: /tmp/upmpdcli.conf Oct 12 08:54:05 technics-pi5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 08:54:05 technics-pi5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 08:54:07 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Oct 12 08:54:07 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:07.789Z] [JP_Radio] [INFO] [IDX_I025] handleBrowseUri: curUri=radiko/progreg/FMJ?ft=20261005060000&to=20261005090000 Oct 12 08:54:09 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:12 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:15 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:17 technics-pi5 volumio[1314]: info: CoreCommandRouter::Close All Modals sent Oct 12 08:54:20 technics-pi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8238. Oct 12 08:54:20 technics-pi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:20 technics-pi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:20 technics-pi5 upmpdcli[10139]: Could not open config: /tmp/upmpdcli.conf Oct 12 08:54:20 technics-pi5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 08:54:20 technics-pi5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 08:54:22 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Oct 12 08:54:22 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:22.954Z] [JP_Radio] [INFO] [IDX_I025] handleBrowseUri: curUri=radiko Oct 12 08:54:23 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:23 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Oct 12 08:54:24 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:24.241Z] [JP_Radio] [INFO] [IDX_I025] handleBrowseUri: curUri=radiko/live Oct 12 08:54:24 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:24.241Z] [JP_Radio] [INFO] [RCT_I005] radioStations Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/TBS Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/QRR Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/LFR Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/INT Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/FMT Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/FMJ Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/JORF Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/BAYFM78 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/NACK5 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/YFM Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/IBS Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/JOAK Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/RN1 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/RN2 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Preloading song: http://localhost:9000/radiko/play/JOAK-FM Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/RN1 in service jp_radio Oct 12 08:54:24 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:24.345Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/RN1 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/RN2 in service jp_radio Oct 12 08:54:24 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:24.396Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/RN2 Oct 12 08:54:24 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/JOAK-FM in service jp_radio Oct 12 08:54:24 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:24.445Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/JOAK-FM Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::ClearQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::clearPlayQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::addQueueItems Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::addQueueItems Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/TBS Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/TBS in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.067Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/TBS Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/QRR Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/QRR in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.067Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/QRR Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/LFR Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/LFR in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.067Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/LFR Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/INT Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/INT in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.067Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/INT Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMT Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/FMT in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.068Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/FMT Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMJ Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/FMJ in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.068Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/FMJ Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::updateTrackBlock Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrackBlock Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPlay Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::play index 5 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::addQueueItems Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::addQueueItems Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/JORF Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/JORF in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.072Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/JORF Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/BAYFM78 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/BAYFM78 in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.072Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/BAYFM78 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/NACK5 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/NACK5 in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.072Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/NACK5 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/YFM Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/YFM in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.072Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/YFM Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/IBS Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/IBS in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.072Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/IBS Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/JOAK Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/JOAK in service jp_radio Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.072Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/JOAK Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::play index undefined Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::startPlaybackTimer Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.073Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9000/radiko/play/FMJ Oct 12 08:54:30 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::updateTrackBlock Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrackBlock Oct 12 08:54:30 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 4 milliseconds Oct 12 08:54:30 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Oct 12 08:54:30 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: sendMpdCommand clear took 0 milliseconds Oct 12 08:54:30 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/FMJ" Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Oct 12 08:54:30 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Oct 12 08:54:30 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:30 technics-pi5 volumio[1314]: error: updateQueue error: null Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Oct 12 08:54:30 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:54:30 technics-pi5 volumio[1314]: info: sendMpdCommand add "http://localhost:9000/radiko/play/FMJ" took 1 milliseconds Oct 12 08:54:30 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:30 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:30 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 08:54:30 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand play Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Oct 12 08:54:30 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Oct 12 08:54:30 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:30 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:30 technics-pi5 volumio[1314]: info: sendMpdCommand play took 0 milliseconds Oct 12 08:54:30 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:30 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.080Z] [JP_Radio] [INFO] [RCT_I002] setupRoutes.get => req.originalUrl=/radiko/play/FMJ Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.080Z] [JP_Radio] [INFO] [RDK_I012] play station=>FMJ Oct 12 08:54:30 technics-pi5 volumio[1314]: error: [2026-10-11T23:54:30.563Z] [JP_Radio] [ERROR] [RDK_E009] genTempChunkM3u8URL error url=https://si-f-radiko.smartstream.ne.jp/so/playlist.m3u8?station_id=FMJ&l=15&lsid=5a2660fb8c1ec7f725edc24f18098ed6&type=c status=403 body=forbidden Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.564Z] [JP_Radio] [INFO] [RDK_I013] Retrying stream fetch with new token Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.564Z] [JP_Radio] [INFO] [RDK_I006] getToken Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.564Z] [JP_Radio] [INFO] [RDK_I008] auth1 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.625Z] [JP_Radio] [INFO] [RDK_I009] getPartialKey Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.625Z] [JP_Radio] [INFO] [RDK_I010] auth2 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.765Z] [JP_Radio] [INFO] [RDK_I007] getToken: areaId=JP13 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.845Z] [JP_Radio] [INFO] [RDK_I014] play: ffmpeg -loglevel error -y -seekable 0 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 2 -headers X-Radiko-Authtoken:***REDACTED*** -i http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D1.v7D6XYJda94HDSrXHqkZWM%26station_id%3DFMJ%26cython%3D1&token=***REDACTED*** -acodec copy -f adts pipe:1 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.851Z] [JP_Radio] [INFO] [SES_I006] ffmpeg=10155 Oct 12 08:54:30 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:30.852Z] [JP_Radio] [INFO] [SES_I007] Streaming started Oct 12 08:54:31 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Oct 12 08:54:33 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Oct 12 08:54:33 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Oct 12 08:54:33 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand status took 16 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Oct 12 08:54:33 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Oct 12 08:54:33 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand status took 17 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand status took 16 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: 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":"48 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus stop Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ------------------------------ 19ms Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Oct 12 08:54:33 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand status took 2 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ------------------------------ 29ms Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ------------------------------ 29ms Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ------------------------------ 13ms Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ------------------------------ 20ms Oct 12 08:54:33 technics-pi5 volumio[1314]: info: ------------------------------ 19ms Oct 12 08:54:33 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:33.852Z] [JP_Radio] [INFO] [RCT_I003] pushSongState: 06:00:00-09:00:00 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:33.852Z] [JP_Radio] [INFO] [RCT_I004] pushSongState: "J-WAVE / 2026/10/12 06:00-09:00 (Live)", now=08:54:13 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":5,"title":"J-WAVE TOKYO MORNING RADIO","artist":"J-WAVE / 2026/10/12 06:00-09:00 (Live)","album":"別所哲也","albumart":"https://program-static.cf.radiko.jp/i7oo4l34t5.png","uri":"http://localhost:9000/radiko/play/FMJ","trackType":"webradio","seek":10453000,"duration":10800,"samplerate":"","bitdepth":"","channels":2,"bitrate":"48 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":75,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Oct 12 08:54:33 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:33 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:33 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:35 technics-pi5 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 12 08:54:35 technics-pi5 systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 08:54:35 technics-pi5 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 08:54:35 technics-pi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8239. Oct 12 08:54:35 technics-pi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:35 technics-pi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:35 technics-pi5 upmpdcli[10197]: Could not open config: /tmp/upmpdcli.conf Oct 12 08:54:35 technics-pi5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 08:54:35 technics-pi5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 08:54:39 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:39 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:39 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:39 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 4 Oct 12 08:54:39 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:39 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:42 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:42 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:42 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 5 Oct 12 08:54:42 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:42 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::stPlaybackTimer Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::updateTrackBlock Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrackBlock Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::serviceStop Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::serviceStop Oct 12 08:54:43 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:43.307Z] [JP_Radio] [INFO] [IDX_I011] stop Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:43 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:43.311Z] [JP_Radio] [INFO] [SES_I001] res.on(close) Oct 12 08:54:43 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:43.311Z] [JP_Radio] [INFO] [SES_I002] SIGTERM sent to ffmpeg group 10155 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: Oct 12 08:54:43 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 11 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:43 technics-pi5 volumio[1314]: info: Oct 12 08:54:43 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:43 technics-pi5 volumio[1314]: info: Oct 12 08:54:43 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand status took 0 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand status took 0 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:43 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:43 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:54:43 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ------------------------------ 5ms Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ------------------------------ 5ms Oct 12 08:54:43 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 2 Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 1 Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:44 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:45 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:47 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:47.280Z] [JP_Radio] [INFO] [SES_I003] ffmpeg process 10155 exited. code=255 signal=null Oct 12 08:54:47 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:50 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:51 technics-pi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8240. Oct 12 08:54:51 technics-pi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:51 technics-pi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:54:51 technics-pi5 upmpdcli[10213]: Could not open config: /tmp/upmpdcli.conf Oct 12 08:54:51 technics-pi5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 08:54:51 technics-pi5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::ClearQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::clearPlayQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::addQueueItems Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::addQueueItems Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/TBS Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/TBS in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.717Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/TBS Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/QRR Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/QRR in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.717Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/QRR Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/LFR Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/LFR in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.717Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/LFR Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/INT Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/INT in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.718Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/INT Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMT Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/FMT in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.718Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/FMT Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/FMJ Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/FMJ in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.718Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/FMJ Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::updateTrackBlock Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrackBlock Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPlay Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::play index 5 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::addQueueItems Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::addQueueItems Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Preload queue cleared Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/JORF Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/JORF in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.723Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/JORF Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/BAYFM78 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/BAYFM78 in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.723Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/BAYFM78 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/NACK5 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/NACK5 in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.723Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/NACK5 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/YFM Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/YFM in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.723Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/YFM Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/IBS Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/IBS in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.724Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/IBS Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Adding Item to queue: http://localhost:9000/radiko/play/JOAK Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Exploding uri http://localhost:9000/radiko/play/JOAK in service jp_radio Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.724Z] [JP_Radio] [INFO] [IDX_I016] explodeUri: uri=http://localhost:9000/radiko/play/JOAK Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::play index undefined Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::startPlaybackTimer Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.724Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9000/radiko/play/FMJ Oct 12 08:54:52 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::updateTrackBlock Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrackBlock Oct 12 08:54:52 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 3 milliseconds Oct 12 08:54:52 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Oct 12 08:54:52 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: sendMpdCommand clear took 1 milliseconds Oct 12 08:54:52 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/FMJ" Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Oct 12 08:54:52 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Oct 12 08:54:52 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:52 technics-pi5 volumio[1314]: error: updateQueue error: null Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Oct 12 08:54:52 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:52 technics-pi5 volumio[1314]: info: sendMpdCommand add "http://localhost:9000/radiko/play/FMJ" took 1 milliseconds Oct 12 08:54:52 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:54:52 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:52 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 08:54:52 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand play Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Oct 12 08:54:52 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Oct 12 08:54:52 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:54:52 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:54:52 technics-pi5 volumio[1314]: info: sendMpdCommand play took 1 milliseconds Oct 12 08:54:52 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:52 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.731Z] [JP_Radio] [INFO] [RCT_I002] setupRoutes.get => req.originalUrl=/radiko/play/FMJ Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.732Z] [JP_Radio] [INFO] [RDK_I012] play station=>FMJ Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.847Z] [JP_Radio] [INFO] [RDK_I014] play: ffmpeg -loglevel error -y -seekable 0 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 2 -headers X-Radiko-Authtoken:***REDACTED*** -i http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D3.je2K4s8XGiuBGnhoLeuUS3%26station_id%3DFMJ%26cython%3D1&token=***REDACTED*** -acodec copy -f adts pipe:1 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.851Z] [JP_Radio] [INFO] [SES_I006] ffmpeg=10214 Oct 12 08:54:52 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:52.852Z] [JP_Radio] [INFO] [SES_I007] Streaming started Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Oct 12 08:54:55 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Oct 12 08:54:55 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Oct 12 08:54:55 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand status took 20 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Oct 12 08:54:55 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand status took 19 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand status took 19 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: 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":"49 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus stop Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ------------------------------ 21ms Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Oct 12 08:54:55 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Oct 12 08:54:55 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":736,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":736,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ------------------------------ 25ms Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ------------------------------ 25ms Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand status took 5 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand status took 5 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ------------------------------ 10ms Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"FMJ","artist":null,"album":null,"uri":"http://localhost:9000/radiko/play/FMJ","trackType":""} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ------------------------------ 15ms Oct 12 08:54:55 technics-pi5 volumio[1314]: info: ------------------------------ 15ms Oct 12 08:54:55 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:55.851Z] [JP_Radio] [INFO] [RCT_I003] pushSongState: 06:00:00-09:00:00 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: [2026-10-11T23:54:55.852Z] [JP_Radio] [INFO] [RCT_I004] pushSongState: "J-WAVE / 2026/10/12 06:00-09:00 (Live)", now=08:54:35 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: STATE SERVICE {"status":"play","position":5,"title":"J-WAVE TOKYO MORNING RADIO","artist":"J-WAVE / 2026/10/12 06:00-09:00 (Live)","album":"別所哲也","albumart":"https://program-static.cf.radiko.jp/i7oo4l34t5.png","uri":"http://localhost:9000/radiko/play/FMJ","trackType":"webradio","seek":10475000,"duration":10800,"samplerate":"","bitdepth":"","channels":2,"bitrate":"49 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":75,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Oct 12 08:54:55 technics-pi5 volumio[1314]: verbose: CURRENT POSITION 5 Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState stateService play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::syncState currentStatus play Oct 12 08:54:55 technics-pi5 volumio[1314]: info: Received an update from plugin. extracting info from payload Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:54:55 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:54:58 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:54:59 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:54:59 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:54:59 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 4 Oct 12 08:54:59 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:54:59 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:00 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:00 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:00 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 5 Oct 12 08:55:00 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:00 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::stPlaybackTimer Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::updateTrackBlock Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrackBlock Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::serviceStop Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::serviceStop Oct 12 08:55:01 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:01.424Z] [JP_Radio] [INFO] [IDX_I011] stop Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:01 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:01.429Z] [JP_Radio] [INFO] [SES_I001] res.on(close) Oct 12 08:55:01 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:01.429Z] [JP_Radio] [INFO] [SES_I002] SIGTERM sent to ffmpeg group 10214 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Oct 12 08:55:01 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 12 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Oct 12 08:55:01 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Oct 12 08:55:01 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand status took 0 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand status took 0 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ------------------------------ 3ms Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:55:01 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:01 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 4 Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:55:01 technics-pi5 volumio[1314]: info: ------------------------------ 5ms Oct 12 08:55:01 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:55:02 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:02.167Z] [JP_Radio] [INFO] [SES_I003] ffmpeg process 10214 exited. code=255 signal=null Oct 12 08:55:02 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:02 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:02 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 2 Oct 12 08:55:02 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:02 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 1 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPlay Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::play index 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::play index undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::startPlaybackTimer Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.628Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9000/radiko/play/TBS Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 0 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand clear took 0 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/TBS" Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: error: updateQueue error: null Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand add "http://localhost:9000/radiko/play/TBS" took 1 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 0ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand play Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand play took 2 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.631Z] [JP_Radio] [INFO] [RCT_I002] setupRoutes.get => req.originalUrl=/radiko/play/TBS Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.631Z] [JP_Radio] [INFO] [RDK_I012] play station=>TBS Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.736Z] [JP_Radio] [INFO] [RDK_I014] play: ffmpeg -loglevel error -y -seekable 0 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 2 -headers X-Radiko-Authtoken:***REDACTED*** -i http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D4.nYMjKaQipzY25eXPxNoxN5%26station_id%3DTBS%26cython%3D1&token=***REDACTED*** -acodec copy -f adts pipe:1 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.741Z] [JP_Radio] [INFO] [SES_I006] ffmpeg=10230 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.741Z] [JP_Radio] [INFO] [SES_I007] Streaming started Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPlay Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::play index 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::play index undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::startPlaybackTimer Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.777Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9000/radiko/play/TBS Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.778Z] [JP_Radio] [INFO] [SES_I001] res.on(close) Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.778Z] [JP_Radio] [INFO] [SES_I002] SIGTERM sent to ffmpeg group 10230 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 1 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand status took 0 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand clear took 0 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand status took 0 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/TBS" Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: error: updateQueue error: null Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand status took 3 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 3ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand add "http://localhost:9000/radiko/play/TBS" took 1 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:03 technics-pi5 volumio[1314]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 5ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 08:55:03 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand play Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Oct 12 08:55:03 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.784Z] [JP_Radio] [INFO] [SES_I003] ffmpeg process 10230 exited. code=null signal=SIGTERM Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 3ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: sendMpdCommand play took 1 milliseconds Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.785Z] [JP_Radio] [INFO] [RCT_I002] setupRoutes.get => req.originalUrl=/radiko/play/TBS Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.785Z] [JP_Radio] [INFO] [RDK_I012] play station=>TBS Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.842Z] [JP_Radio] [INFO] [RDK_I014] play: ffmpeg -loglevel error -y -seekable 0 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 2 -headers X-Radiko-Authtoken:***REDACTED*** -i http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D3.nHeGHcbACSc397gebnmmg5%26station_id%3DTBS%26cython%3D1&token=***REDACTED*** -acodec copy -f adts pipe:1 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.847Z] [JP_Radio] [INFO] [SES_I006] ffmpeg=10231 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:03.847Z] [JP_Radio] [INFO] [SES_I007] Streaming started Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:03 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPlay Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::play index 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::play index undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::startPlaybackTimer Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.130Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9000/radiko/play/INT Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.131Z] [JP_Radio] [INFO] [SES_I001] res.on(close) Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.131Z] [JP_Radio] [INFO] [SES_I002] SIGTERM sent to ffmpeg group 10231 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 2 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:04 technics-pi5 volumio[1314]: error: [2026-10-11T23:55:04.133Z] [JP_Radio] [ERROR] [SES_E003] ffmpeg stderr: http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D3.nHeGHcbACSc397gebnmmg5%26station_id%3DTBS%26cython%3D1&token=Ky8MlLbM5bxwu3bFtz8VFg: Immediate exit requested Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand clear took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/INT" Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: error: updateQueue error: null Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand add "http://localhost:9000/radiko/play/INT" took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 0ms Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:55:04 technics-pi5 volumio[1314]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 6ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand play Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand play took 2 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.139Z] [JP_Radio] [INFO] [RCT_I002] setupRoutes.get => req.originalUrl=/radiko/play/INT Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.139Z] [JP_Radio] [INFO] [RDK_I012] play station=>INT Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.142Z] [JP_Radio] [INFO] [SES_I003] ffmpeg process 10231 exited. code=1 signal=null Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.217Z] [JP_Radio] [INFO] [RDK_I014] play: ffmpeg -loglevel error -y -seekable 0 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 2 -headers X-Radiko-Authtoken:***REDACTED*** -i http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D2.knvDD9PpVo8g9Ub2YyBbxA%26station_id%3DINT%26cython%3D1&token=***REDACTED*** -acodec copy -f adts pipe:1 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.221Z] [JP_Radio] [INFO] [SES_I006] ffmpeg=10232 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.222Z] [JP_Radio] [INFO] [SES_I007] Streaming started Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPlay Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::play index 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::play index undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::startPlaybackTimer Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.347Z] [JP_Radio] [INFO] [IDX_I009] clearAddPlayTrack: uri=http://localhost:9000/radiko/play/INT Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.348Z] [JP_Radio] [INFO] [SES_I001] res.on(close) Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.348Z] [JP_Radio] [INFO] [SES_I002] SIGTERM sent to ffmpeg group 10232 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand stop took 2 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces state update: player Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::getState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand status Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand clear took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand add "http://localhost:9000/radiko/play/INT" Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: error: updateQueue error: null Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand status took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand add "http://localhost:9000/radiko/play/INT" took 1 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 1ms Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseState Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::parseTrackInfo Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ControllerMpd::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::servicePushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::pushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushState Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jp_radio Received mpd Oct 12 08:55:04 technics-pi5 volumio[1314]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 4ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 3ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 5ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 08:55:04 technics-pi5 volumio[1314]: verbose: ControllerMpd::sendMpdCommand play Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.356Z] [JP_Radio] [INFO] [SES_I003] ffmpeg process 10232 exited. code=null signal=SIGTERM Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 5ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: sendMpdCommand play took 3 milliseconds Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 3ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 2ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Oct 12 08:55:04 technics-pi5 volumio[1314]: ---------------------------- MPD announces system playlist update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Ignoring MPD Status Update Oct 12 08:55:04 technics-pi5 volumio[1314]: info: ------------------------------ 0ms Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.357Z] [JP_Radio] [INFO] [RCT_I002] setupRoutes.get => req.originalUrl=/radiko/play/INT Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.357Z] [JP_Radio] [INFO] [RDK_I012] play station=>INT Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.408Z] [JP_Radio] [INFO] [RDK_I014] play: ffmpeg -loglevel error -y -seekable 0 -reconnect 1 -reconnect_streamed 1 -reconnect_delay_max 2 -headers X-Radiko-Authtoken:***REDACTED*** -i http://127.0.0.1:9000/radiko/medialist-proxy?url=https%3A%2F%2Fsi-f-radiko.smartstream.ne.jp%2Fmedialist%3Fsession%3D2.pXiiryMy6bLhu7SGCBC5bk%26station_id%3DINT%26cython%3D1&token=***REDACTED*** -acodec copy -f adts pipe:1 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.413Z] [JP_Radio] [INFO] [SES_I006] ffmpeg=10247 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:04.413Z] [JP_Radio] [INFO] [SES_I007] Streaming started Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:04 technics-pi5 volumio[1314]: info: Discovery: Remote connection error: 192.168.24.158 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:04 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::removeQueueItem Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::stop Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::removeQueueItem 0 Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CorePlayQueue::saveQueue Oct 12 08:55:05 technics-pi5 volumio[1314]: info: CoreCommandRouter::volumioPushQueue Oct 12 08:55:06 technics-pi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8241. Oct 12 08:55:06 technics-pi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:55:06 technics-pi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 08:55:06 technics-pi5 upmpdcli[10248]: Could not open config: /tmp/upmpdcli.conf Oct 12 08:55:06 technics-pi5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 08:55:06 technics-pi5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 08:55:06 technics-pi5 volumio[1314]: info: CorePlayQueue::getTrack 0 Oct 12 08:55:06 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:06.742Z] [JP_Radio] [INFO] [RCT_I003] pushSongState: 08:50:00-09:00:00 Oct 12 08:55:06 technics-pi5 volumio[1314]: info: [2026-10-11T23:55:06.742Z] [JP_Radio] [INFO] [RCT_I004] pushSongState: "interfm / 2026/10/12 08:50-09:00 (Live)", now=08:54:46 Oct 12 08:55:06 technics-pi5 volumio[1314]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 08:55:06 technics-pi5 volumio[1314]: TypeError: Cannot set properties of undefined (setting 'name') Oct 12 08:55:06 technics-pi5 volumio[1314]: at #pushSongState (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:263:32) Oct 12 08:55:06 technics-pi5 volumio[1314]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 12 08:55:06 technics-pi5 volumio[1314]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 08:55:06 technics-pi5 sudo[10263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 08:54' Oct 12 08:55:06 technics-pi5 sudo[10263]: 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"