-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sun 2024-10-13 17:56:42 JST. -- Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: Playing Remote Device: http://192.168.10.117 Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: Oct 13 17:55:05 volumio-rp4b volumio[1006]: ---------------------------- Client requests Volumio play Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPlay Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CoreStateMachine::play index undefined Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CoreStateMachine::startPlaybackTimer Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: [1728809705507] ControllerWebradio::clearAddPlayTrack Oct 13 17:55:05 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: sendMpdCommand stop took 5 milliseconds Oct 13 17:55:05 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: Done playing: Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: Oct 13 17:55:05 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: sendMpdCommand clear took 4 milliseconds Oct 13 17:55:05 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:9000/radiko/CBC" Oct 13 17:55:05 volumio-rp4b volumio[1006]: error: updateQueue error: null Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: ------------------------------ 5ms Oct 13 17:55:05 volumio-rp4b volumio[1006]: info: JP_Radio::playing CBC Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:9000/radiko/CBC" Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Oct 13 17:55:06 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: sendMpdCommand add "http://127.0.0.1:9000/radiko/CBC" took 2 milliseconds Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: ------------------------------ 4ms Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: JP_Radio::stop playing CBC Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: sendMpdCommand play took 4 milliseconds Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: JP_Radio::playing CBC Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Playing Remote Device: http://192.168.10.117 Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Oct 13 17:55:06 volumio-rp4b volumio[1006]: ---------------------------- Client requests Volumio play Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPlay Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CoreStateMachine::play index undefined Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CoreStateMachine::startPlaybackTimer Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: [1728809706552] ControllerWebradio::clearAddPlayTrack Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Oct 13 17:55:06 volumio-rp4b volumio[1006]: ---------------------------- MPD announces state update: player Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: sendMpdCommand stop took 5 milliseconds Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: ControllerMpd::getState Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Done playing: Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: JP_Radio::stop playing CBC Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: sendMpdCommand status took 11 milliseconds Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseState Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Oct 13 17:55:06 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: sendMpdCommand clear took 14 milliseconds Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:9000/radiko/CBC" Oct 13 17:55:06 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:55:06 volumio-rp4b volumio[1006]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: ------------------------------ 20ms Oct 13 17:55:06 volumio-rp4b volumio[1006]: error: updateQueue error: null Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: ------------------------------ 6ms Oct 13 17:55:06 volumio-rp4b volumio[1006]: info: JP_Radio::playing CBC Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:9000/radiko/CBC" Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Oct 13 17:55:07 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand add "http://127.0.0.1:9000/radiko/CBC" took 2 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: ------------------------------ 2ms Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: JP_Radio::stop playing CBC Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand play took 2 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: JP_Radio::playing CBC Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Playing Remote Device: http://192.168.10.117 Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Oct 13 17:55:07 volumio-rp4b volumio[1006]: ---------------------------- Client requests Volumio play Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPlay Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreStateMachine::play index undefined Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreStateMachine::startPlaybackTimer Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: [1728809707301] ControllerWebradio::clearAddPlayTrack Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Oct 13 17:55:07 volumio-rp4b volumio[1006]: ---------------------------- MPD announces state update: player Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand stop took 10 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: ControllerMpd::getState Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Done playing: Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Oct 13 17:55:07 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand status took 5 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand clear took 4 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseState Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:9000/radiko/CBC" Oct 13 17:55:07 volumio-rp4b volumio[1006]: error: updateQueue error: null Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: ------------------------------ 6ms Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:55:07 volumio-rp4b volumio[1006]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: ------------------------------ 12ms Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: JP_Radio::playing CBC Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: JP_Radio::stop playing CBC Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:9000/radiko/CBC" Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Oct 13 17:55:07 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand add "http://127.0.0.1:9000/radiko/CBC" took 2 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 17:55:07 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: ------------------------------ 3ms Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: JP_Radio::stop playing CBC Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: sendMpdCommand play took 2 milliseconds Oct 13 17:55:07 volumio-rp4b volumio[1006]: info: JP_Radio::playing CBC Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Oct 13 17:55:08 volumio-rp4b volumio[1006]: ---------------------------- MPD announces state update: player Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ControllerMpd::getState Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Oct 13 17:55:08 volumio-rp4b volumio[1006]: ---------------------------- MPD announces state update: player Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: sendMpdCommand status took 10 milliseconds Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ControllerMpd::getState Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseState Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: sendMpdCommand status took 3 milliseconds Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 17:55:08 volumio-rp4b kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseState Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ControllerMpd::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::servicePushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBC","artist":null,"album":null,"uri":"http://127.0.0.1:9000/radiko/CBC","trackType":""} Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: CURRENT POSITION 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState stateService play Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ------------------------------ 33ms Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Oct 13 17:55:08 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Oct 13 17:55:08 volumio-rp4b volumio[1006]: ---------------------------- MPD announces state update: player Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ControllerMpd::getState Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ControllerMpd::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::servicePushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBC","artist":"CBC RADIO","album":null,"uri":"http://127.0.0.1:9000/radiko/CBC","trackType":""} Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: CURRENT POSITION 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState stateService play Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState currentStatus play Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Received an update from plugin. extracting info from payload Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output update for this device Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output update for this device Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ------------------------------ 118ms Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ------------------------------ 98ms Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: sendMpdCommand status took 95 milliseconds Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseState Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: sendMpdCommand playlistinfo took 86 milliseconds Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ControllerMpd::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::servicePushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"CBC","artist":"CBC RADIO","album":null,"uri":"http://127.0.0.1:9000/radiko/CBC","trackType":""} Oct 13 17:55:08 volumio-rp4b volumio[1006]: verbose: CURRENT POSITION 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState stateService play Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState currentStatus play Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: Received an update from plugin. extracting info from payload Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output update for this device Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreStateMachine::pushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output update for this device Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: ------------------------------ 263ms Oct 13 17:55:08 volumio-rp4b volumio[1006]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 13 17:55:10 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 13 17:55:10 volumio-rp4b volumio[1006]: info: Pausing Remote Device: http://192.168.10.133 Oct 13 17:55:10 volumio-rp4b volumio[1006]: info: Done pausing: Oct 13 17:55:10 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: Oct 13 17:55:13 volumio-rp4b volumio[1006]: ---------------------------- MPD announces system playlist update Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: Ignoring MPD Status Update Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: Oct 13 17:55:13 volumio-rp4b volumio[1006]: ---------------------------- MPD announces state update: player Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: ControllerMpd::getState Oct 13 17:55:13 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: ------------------------------ 4ms Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: sendMpdCommand status took 3 milliseconds Oct 13 17:55:13 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseState Oct 13 17:55:13 volumio-rp4b volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 17:55:13 volumio-rp4b volumio[1006]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: ControllerMpd::pushState Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreCommandRouter::servicePushState Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 13 17:55:13 volumio-rp4b volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6489,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"42 Kbps","isStreaming":false,"title":"河原崎辰也　清水藍 - 河原崎辰也 いくしかないだろう！","artist":"CBC RADIO","album":null,"uri":"http://127.0.0.1:9000/radiko/CBC","trackType":""} Oct 13 17:55:13 volumio-rp4b volumio[1006]: verbose: CURRENT POSITION 0 Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState stateService play Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreStateMachine::syncState currentStatus play Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: Received an update from plugin. extracting info from payload Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreStateMachine::pushState Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output update for this device Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreStateMachine::pushState Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output update for this device Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: MRS: Pushing multiroomSync output Oct 13 17:55:13 volumio-rp4b volumio[1006]: info: ------------------------------ 65ms Oct 13 17:55:25 volumio-rp4b volumio[1006]: info: Tunnel connection is inactive, restarting it Oct 13 17:55:25 volumio-rp4b volumio[1006]: info: Starting Tunnel 1 Oct 13 17:55:25 volumio-rp4b volumio[1006]: info: Starting Tunnel Connection Checker Oct 13 17:55:25 volumio-rp4b sudo[2113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 13 17:55:25 volumio-rp4b sudo[2113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:55:25 volumio-rp4b autossh[1916]: received signal to exit (15) Oct 13 17:55:25 volumio-rp4b systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 13 17:55:25 volumio-rp4b systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 13 17:55:25 volumio-rp4b volumio[1006]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 13 17:55:25 volumio-rp4b systemd[1]: sshtunnel.service: Succeeded. Oct 13 17:55:25 volumio-rp4b systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 13 17:55:25 volumio-rp4b volumio[1006]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 13 17:55:25 volumio-rp4b systemd[1]: Started MyVolumio SSH Tunnel. Oct 13 17:55:25 volumio-rp4b sudo[2113]: pam_unix(sudo:session): session closed for user root Oct 13 17:55:25 volumio-rp4b volumio[1006]: info: Remote SSH Started Oct 13 17:55:25 volumio-rp4b autossh[2116]: port set to 0, monitoring disabled Oct 13 17:55:25 volumio-rp4b autossh[2116]: starting ssh (count 1) Oct 13 17:55:25 volumio-rp4b autossh[2116]: ssh child pid is 2119 Oct 13 17:55:25 volumio-rp4b volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 13 17:55:25 volumio-rp4b volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 13 17:55:25 volumio-rp4b volumiossh-tunnel[2115]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Oct 13 17:55:52 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:55:52 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:55:54 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:55:54 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:55:54 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:55:59 volumio-rp4b volumio[1006]: info: MRS: Updating multiroomSync output Oct 13 17:56:00 volumio-rp4b ntpd[813]: Soliciting pool server 133.243.238.243 Oct 13 17:56:00 volumio-rp4b ntpd[813]: Soliciting pool server 162.159.200.1 Oct 13 17:56:00 volumio-rp4b ntpd[813]: Soliciting pool server 133.130.121.141 Oct 13 17:56:03 volumio-rp4b ntpd[813]: Soliciting pool server 163.44.97.196 Oct 13 17:56:41 volumio-rp4b volumio[1006]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 13 17:56:41 volumio-rp4b volumio[1006]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 13 17:56:41 volumio-rp4b volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:56:41 volumio-rp4b volumio[1006]: TypeError: Cannot read property 'then' of undefined Oct 13 17:56:41 volumio-rp4b volumio[1006]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 13 17:56:41 volumio-rp4b volumio[1006]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Oct 13 17:56:41 volumio-rp4b volumio[1006]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1442:26) Oct 13 17:56:41 volumio-rp4b volumio[1006]: at Socket.emit (events.js:315:20) Oct 13 17:56:41 volumio-rp4b volumio[1006]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 13 17:56:41 volumio-rp4b volumio[1006]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 13 17:56:41 volumio-rp4b volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:56:42 volumio-rp4b sudo[2215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 17:55 Oct 13 17:56:42 volumio-rp4b sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"