-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Wed 2024-05-15 02:27:16 UTC. -- May 15 02:26:03 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:04 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::getTrack 11 May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::serviceStop May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::getTrack 11 May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::serviceStop May 15 02:26:04 volumio volumio[776]: info: ControllerMpd::stop May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:26:04 volumio volumio[776]: info: Adding Item to queue: https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master.m3u8 May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:04 volumio volumio[776]: info: CoreCommandRouter::volumioPlay May 15 02:26:04 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::play index 0 May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:04 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::play index undefined May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:04 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer May 15 02:26:04 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:04 volumio volumio[776]: info: [1715739964757] ControllerWebradio::clearAddPlayTrack May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:04 volumio volumio[776]: info: May 15 02:26:04 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:04 volumio volumio[776]: info: sendMpdCommand stop took 113 milliseconds May 15 02:26:04 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:04 volumio volumio[776]: info: sendMpdCommand stop took 26 milliseconds May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear May 15 02:26:04 volumio volumio[776]: info: May 15 02:26:04 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:04 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:04 volumio volumio[776]: info: sendMpdCommand status took 12 milliseconds May 15 02:26:04 volumio volumio[776]: info: sendMpdCommand clear took 8 milliseconds May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand load "https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master.m3u8" May 15 02:26:04 volumio volumio[776]: info: May 15 02:26:04 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:04 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:04 volumio volumio[776]: error: updateQueue error: null May 15 02:26:04 volumio volumio[776]: info: ------------------------------ 25ms May 15 02:26:04 volumio volumio[776]: info: sendMpdCommand playlistinfo took 21 milliseconds May 15 02:26:04 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:04 volumio volumio[776]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 02:26:04 volumio volumio[776]: info: ------------------------------ 46ms May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand load "https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master.m3u8" took 251 milliseconds May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play May 15 02:26:05 volumio volumio[776]: info: May 15 02:26:05 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:05 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand status took 253 milliseconds May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand play took 6 milliseconds May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 02:26:05 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: No code May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 335ms May 15 02:26:05 volumio volumio[776]: info: May 15 02:26:05 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:05 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 93ms May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 10ms May 15 02:26:05 volumio volumio[776]: info: May 15 02:26:05 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:05 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:05 volumio volumio[776]: info: May 15 02:26:05 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:05 volumio volumio[776]: info: May 15 02:26:05 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand status took 15 milliseconds May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:05 volumio volumio[776]: info: May 15 02:26:05 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand status took 20 milliseconds May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand status took 15 milliseconds May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand playlistinfo took 13 milliseconds May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand status took 9 milliseconds May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:05 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 94ms May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand playlistinfo took 54 milliseconds May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand playlistinfo took 53 milliseconds May 15 02:26:05 volumio volumio[776]: info: sendMpdCommand playlistinfo took 49 milliseconds May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:05 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:05 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:26:05 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:05 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:26:05 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:05 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:26:05 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:05 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 248ms May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 240ms May 15 02:26:05 volumio volumio[776]: info: ------------------------------ 233ms May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:10 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::serviceStop May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::serviceStop May 15 02:26:10 volumio volumio[776]: info: [1715739970046] ControllerWebradio::stop May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:26:10 volumio volumio[776]: info: Adding Item to queue: http://mtist.as.smartstream.ne.jp/30058/livestream/playlist.m3u8 May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPlay May 15 02:26:10 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::play index 0 May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:10 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::play index undefined May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: info: [1715739970121] ControllerWebradio::clearAddPlayTrack May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand stop took 158 milliseconds May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand stop took 114 milliseconds May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:10 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand status took 21 milliseconds May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand clear took 19 milliseconds May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand status took 16 milliseconds May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand load "http://mtist.as.smartstream.ne.jp/30058/livestream/playlist.m3u8" May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 58ms May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:10 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:10 volumio volumio[776]: error: updateQueue error: null May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 53ms May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand playlistinfo took 45 milliseconds May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:10 volumio volumio[776]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 78ms May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:10 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand load "http://mtist.as.smartstream.ne.jp/30058/livestream/playlist.m3u8" took 57 milliseconds May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 21ms May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:10 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 13ms May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand play took 9 milliseconds May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 8ms May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:10 volumio volumio[776]: info: May 15 02:26:10 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand status took 22 milliseconds May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand status took 33 milliseconds May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand clearerror took 16 milliseconds May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand playlistinfo took 14 milliseconds May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"http://mtist.as.smartstream.ne.jp/30058/livestream/chunklist.m3u8","trackType":"m3u8"} May 15 02:26:10 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: No code May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 111ms May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand clearerror took 91 milliseconds May 15 02:26:10 volumio volumio[776]: info: sendMpdCommand playlistinfo took 87 milliseconds May 15 02:26:10 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:10 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:10 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"http://mtist.as.smartstream.ne.jp/30058/livestream/chunklist.m3u8","trackType":"m3u8"} May 15 02:26:10 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: No code May 15 02:26:10 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:10 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:10 volumio volumio[776]: info: ------------------------------ 191ms May 15 02:26:12 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:12 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:12 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:12 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:26:12 volumio volumio[776]: info: Adding Item to queue: https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master.m3u8 May 15 02:26:12 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:12 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:12 volumio volumio[776]: info: CoreCommandRouter::volumioPlay May 15 02:26:12 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::play index 0 May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:12 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::play index undefined May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer May 15 02:26:12 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:12 volumio volumio[776]: info: [1715739972500] ControllerWebradio::clearAddPlayTrack May 15 02:26:12 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:12 volumio volumio[776]: info: sendMpdCommand stop took 10 milliseconds May 15 02:26:12 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear May 15 02:26:12 volumio volumio[776]: info: May 15 02:26:12 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:12 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:12 volumio volumio[776]: info: sendMpdCommand clear took 4 milliseconds May 15 02:26:12 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand load "https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master.m3u8" May 15 02:26:12 volumio volumio[776]: info: May 15 02:26:12 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:12 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:12 volumio volumio[776]: error: updateQueue error: null May 15 02:26:12 volumio volumio[776]: info: ------------------------------ 11ms May 15 02:26:12 volumio volumio[776]: info: sendMpdCommand load "https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master.m3u8" took 25 milliseconds May 15 02:26:12 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 02:26:12 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play May 15 02:26:12 volumio volumio[776]: info: May 15 02:26:12 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:12 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:12 volumio volumio[776]: info: ------------------------------ 30ms May 15 02:26:12 volumio volumio[776]: info: May 15 02:26:12 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:12 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:12 volumio volumio[776]: info: sendMpdCommand play took 12 milliseconds May 15 02:26:12 volumio volumio[776]: info: ------------------------------ 11ms May 15 02:26:12 volumio volumio[776]: info: ------------------------------ 6ms May 15 02:26:13 volumio volumio[776]: info: May 15 02:26:13 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:13 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 15 02:26:13 volumio volumio[776]: info: May 15 02:26:13 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand status took 14 milliseconds May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:13 volumio volumio[776]: info: May 15 02:26:13 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand status took 22 milliseconds May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand playlistinfo took 12 milliseconds May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:13 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: ------------------------------ 86ms May 15 02:26:13 volumio volumio[776]: info: May 15 02:26:13 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand status took 63 milliseconds May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand playlistinfo took 55 milliseconds May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand status took 13 milliseconds May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:13 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:26:13 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: ------------------------------ 155ms May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand playlistinfo took 80 milliseconds May 15 02:26:13 volumio volumio[776]: info: sendMpdCommand playlistinfo took 78 milliseconds May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:13 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:13 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:26:13 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"45 Kbps","isStreaming":false,"title":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023546/nhkradiruikfm/master48k.m3u8","trackType":"m3u8"} May 15 02:26:13 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:26:13 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:13 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:13 volumio volumio[776]: info: ------------------------------ 282ms May 15 02:26:13 volumio volumio[776]: info: ------------------------------ 229ms May 15 02:26:37 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio May 15 02:26:37 volumio volumio[776]: info: {"name":"FMあばしり"} May 15 02:26:37 volumio volumio[776]: info: Saving local item my-web-radio May 15 02:26:37 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:40 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::serviceStop May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::serviceStop May 15 02:26:40 volumio volumio[776]: info: [1715740000187] ControllerWebradio::stop May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:26:40 volumio volumio[776]: info: Adding Item to queue: http://rakuten.streamguys1.com/ottava1_b?args=widget May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioPlay May 15 02:26:40 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::play index 0 May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:40 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::play index undefined May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:40 volumio volumio[776]: info: [1715740000250] ControllerWebradio::clearAddPlayTrack May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:40 volumio volumio[776]: info: sendMpdCommand stop took 169 milliseconds May 15 02:26:40 volumio volumio[776]: info: May 15 02:26:40 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:40 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:40 volumio volumio[776]: info: sendMpdCommand stop took 110 milliseconds May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear May 15 02:26:40 volumio volumio[776]: info: May 15 02:26:40 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:40 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:40 volumio volumio[776]: info: May 15 02:26:40 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:40 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:40 volumio volumio[776]: info: sendMpdCommand status took 12 milliseconds May 15 02:26:40 volumio volumio[776]: info: sendMpdCommand clear took 9 milliseconds May 15 02:26:40 volumio volumio[776]: info: sendMpdCommand status took 6 milliseconds May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand load "http://rakuten.streamguys1.com/ottava1_b?args=widget" May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:40 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:40 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:40 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:40 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:40 volumio volumio[776]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 15 02:26:40 volumio volumio[776]: info: ------------------------------ 46ms May 15 02:26:40 volumio volumio[776]: info: May 15 02:26:40 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:40 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:40 volumio volumio[776]: error: updateQueue error: null May 15 02:26:40 volumio volumio[776]: info: ------------------------------ 50ms May 15 02:26:40 volumio volumio[776]: info: sendMpdCommand playlistinfo took 46 milliseconds May 15 02:26:40 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:40 volumio volumio[776]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 02:26:40 volumio volumio[776]: info: ------------------------------ 66ms May 15 02:26:41 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand add "http://rakuten.streamguys1.com/ottava1_b?args=widget" May 15 02:26:41 volumio volumio[776]: error: updateQueue error: null May 15 02:26:41 volumio volumio[776]: info: May 15 02:26:41 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:41 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:41 volumio volumio[776]: info: ------------------------------ 1190ms May 15 02:26:41 volumio volumio[776]: info: sendMpdCommand add "http://rakuten.streamguys1.com/ottava1_b?args=widget" took 5 milliseconds May 15 02:26:41 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 02:26:41 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play May 15 02:26:41 volumio volumio[776]: info: May 15 02:26:41 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:41 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:41 volumio volumio[776]: info: ------------------------------ 22ms May 15 02:26:41 volumio volumio[776]: info: sendMpdCommand play took 19 milliseconds May 15 02:26:41 volumio volumio[776]: info: ------------------------------ 16ms May 15 02:26:42 volumio volumio[776]: info: May 15 02:26:42 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:42 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:42 volumio volumio[776]: info: May 15 02:26:42 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:42 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 02:26:42 volumio volumio[776]: info: sendMpdCommand status took 16 milliseconds May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 02:26:42 volumio volumio[776]: info: sendMpdCommand status took 27 milliseconds May 15 02:26:42 volumio volumio[776]: info: sendMpdCommand clearerror took 16 milliseconds May 15 02:26:42 volumio volumio[776]: info: sendMpdCommand playlistinfo took 13 milliseconds May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:42 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:42 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:42 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ottava1_b?args=widget","artist":null,"album":null,"uri":"http://rakuten.streamguys1.com/ottava1_b?args=widget","trackType":""} May 15 02:26:42 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:42 volumio volumio[776]: info: No code May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:42 volumio volumio[776]: info: ------------------------------ 96ms May 15 02:26:42 volumio volumio[776]: info: sendMpdCommand clearerror took 81 milliseconds May 15 02:26:42 volumio volumio[776]: info: sendMpdCommand playlistinfo took 78 milliseconds May 15 02:26:42 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:42 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:42 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:42 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ottava1_b?args=widget","artist":null,"album":null,"uri":"http://rakuten.streamguys1.com/ottava1_b?args=widget","trackType":""} May 15 02:26:42 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:42 volumio volumio[776]: info: No code May 15 02:26:42 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:42 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:42 volumio volumio[776]: info: ------------------------------ 175ms May 15 02:26:44 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:44 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:44 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:44 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:26:44 volumio volumio[776]: info: Adding Item to queue: http://rakuten.streamguys1.com/ottava1_b?args=widget May 15 02:26:44 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:26:44 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:26:44 volumio volumio[776]: info: CoreCommandRouter::volumioPlay May 15 02:26:44 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::play index 0 May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:44 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::play index undefined May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:44 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer May 15 02:26:44 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:44 volumio volumio[776]: info: [1715740004302] ControllerWebradio::clearAddPlayTrack May 15 02:26:44 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:26:44 volumio volumio[776]: info: sendMpdCommand stop took 12 milliseconds May 15 02:26:44 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear May 15 02:26:44 volumio volumio[776]: info: May 15 02:26:44 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:44 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:44 volumio volumio[776]: info: sendMpdCommand clear took 5 milliseconds May 15 02:26:44 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand load "http://rakuten.streamguys1.com/ottava1_b?args=widget" May 15 02:26:44 volumio volumio[776]: info: May 15 02:26:44 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:44 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:44 volumio volumio[776]: error: updateQueue error: null May 15 02:26:44 volumio volumio[776]: info: ------------------------------ 10ms May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand add "http://rakuten.streamguys1.com/ottava1_b?args=widget" May 15 02:26:45 volumio volumio[776]: error: updateQueue error: null May 15 02:26:45 volumio volumio[776]: info: May 15 02:26:45 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:45 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:45 volumio volumio[776]: info: ------------------------------ 690ms May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand add "http://rakuten.streamguys1.com/ottava1_b?args=widget" took 5 milliseconds May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play May 15 02:26:45 volumio volumio[776]: info: May 15 02:26:45 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:26:45 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:26:45 volumio volumio[776]: info: ------------------------------ 12ms May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand play took 8 milliseconds May 15 02:26:45 volumio volumio[776]: info: ------------------------------ 6ms May 15 02:26:45 volumio volumio[776]: info: May 15 02:26:45 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:45 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:45 volumio volumio[776]: info: May 15 02:26:45 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:26:45 volumio volumio[776]: info: ControllerMpd::getState May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand status took 24 milliseconds May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand status took 23 milliseconds May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand clearerror took 21 milliseconds May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand clearerror took 18 milliseconds May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand playlistinfo took 9 milliseconds May 15 02:26:45 volumio volumio[776]: info: sendMpdCommand playlistinfo took 7 milliseconds May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:45 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:26:45 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:45 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:45 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ottava1_b?args=widget","artist":null,"album":null,"uri":"http://rakuten.streamguys1.com/ottava1_b?args=widget","trackType":""} May 15 02:26:45 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:45 volumio volumio[776]: info: No code May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:45 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:45 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:26:45 volumio volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ottava1_b?args=widget","artist":null,"album":null,"uri":"http://rakuten.streamguys1.com/ottava1_b?args=widget","trackType":""} May 15 02:26:45 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::syncState stateService stop May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:45 volumio volumio[776]: info: No code May 15 02:26:45 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:26:45 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:26:45 volumio volumio[776]: info: ------------------------------ 161ms May 15 02:26:45 volumio volumio[776]: info: ------------------------------ 159ms May 15 02:27:00 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio May 15 02:27:00 volumio volumio[776]: info: {"name":"OTTAVA"} May 15 02:27:00 volumio volumio[776]: info: Saving local item my-web-radio May 15 02:27:00 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 15 02:27:02 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:27:02 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:27:02 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:02 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:27:02 volumio volumio[776]: info: Adding Item to queue: https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master.m3u8 May 15 02:27:02 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:27:02 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:27:02 volumio volumio[776]: info: CoreCommandRouter::volumioPlay May 15 02:27:02 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::play index 0 May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:27:02 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::play index undefined May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::startPlaybackTimer May 15 02:27:02 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:02 volumio volumio[776]: info: [1715740022767] ControllerWebradio::clearAddPlayTrack May 15 02:27:02 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:27:02 volumio volumio[776]: info: sendMpdCommand stop took 13 milliseconds May 15 02:27:02 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand clear May 15 02:27:02 volumio volumio[776]: info: May 15 02:27:02 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:27:02 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:27:02 volumio volumio[776]: info: sendMpdCommand clear took 7 milliseconds May 15 02:27:02 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand load "https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master.m3u8" May 15 02:27:02 volumio volumio[776]: info: May 15 02:27:02 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:27:02 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:27:02 volumio volumio[776]: error: updateQueue error: null May 15 02:27:02 volumio volumio[776]: info: ------------------------------ 33ms May 15 02:27:02 volumio volumio[776]: info: sendMpdCommand load "https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master.m3u8" took 36 milliseconds May 15 02:27:02 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 02:27:02 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand play May 15 02:27:02 volumio volumio[776]: info: May 15 02:27:02 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:27:02 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:27:02 volumio volumio[776]: info: ------------------------------ 39ms May 15 02:27:02 volumio volumio[776]: info: sendMpdCommand play took 6 milliseconds May 15 02:27:02 volumio volumio[776]: info: May 15 02:27:02 volumio volumio[776]: ---------------------------- MPD announces system playlist update May 15 02:27:02 volumio volumio[776]: info: Ignoring MPD Status Update May 15 02:27:02 volumio volumio[776]: info: ------------------------------ 36ms May 15 02:27:02 volumio volumio[776]: info: ------------------------------ 37ms May 15 02:27:03 volumio volumio[776]: info: May 15 02:27:03 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:27:03 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::getState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:27:03 volumio volumio[776]: info: May 15 02:27:03 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::getState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand status took 15 milliseconds May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:27:03 volumio volumio[776]: info: May 15 02:27:03 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::getState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand status took 21 milliseconds May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand playlistinfo took 9 milliseconds May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master48k.m3u8","trackType":"m3u8"} May 15 02:27:03 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus stop May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: ------------------------------ 78ms May 15 02:27:03 volumio volumio[776]: info: May 15 02:27:03 volumio volumio[776]: ---------------------------- MPD announces state update: player May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::getState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand status May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand status took 52 milliseconds May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand playlistinfo took 48 milliseconds May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master48k.m3u8","trackType":"m3u8"} May 15 02:27:03 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:27:03 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: ------------------------------ 134ms May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand status took 84 milliseconds May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand playlistinfo took 73 milliseconds May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseState May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: 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":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master48k.m3u8","trackType":"m3u8"} May 15 02:27:03 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:27:03 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: ------------------------------ 193ms May 15 02:27:03 volumio volumio[776]: info: sendMpdCommand playlistinfo took 60 milliseconds May 15 02:27:03 volumio volumio[776]: verbose: ControllerMpd::parseTrackInfo May 15 02:27:03 volumio volumio[776]: info: ControllerMpd::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::servicePushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"54 Kbps","isStreaming":false,"title":"master48k.m3u8","artist":null,"album":null,"uri":"https://radio-stream.nhk.jp/hls/live/2023545/nhkradiruikr1/master48k.m3u8","trackType":"m3u8"} May 15 02:27:03 volumio volumio[776]: verbose: CURRENT POSITION 0 May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState stateService play May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::syncState currentStatus play May 15 02:27:03 volumio volumio[776]: info: Received an update from plugin. extracting info from payload May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:03 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:03 volumio volumio[776]: info: ------------------------------ 217ms May 15 02:27:08 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri May 15 02:27:12 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri May 15 02:27:15 volumio volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::ClearQueue May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::stop May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 02:27:15 volumio volumio[776]: verbose: UNSET VOLATILE May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::stPlaybackTimer May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::updateTrackBlock May 15 02:27:15 volumio volumio[776]: info: CorePlayQueue::getTrackBlock May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::pushState May 15 02:27:15 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:15 volumio volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 02:27:15 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::serviceStop May 15 02:27:15 volumio volumio[776]: info: CorePlayQueue::getTrack 0 May 15 02:27:15 volumio volumio[776]: info: CoreCommandRouter::serviceStop May 15 02:27:15 volumio volumio[776]: info: [1715740035178] ControllerWebradio::stop May 15 02:27:15 volumio volumio[776]: verbose: ControllerMpd::sendMpdCommand stop May 15 02:27:15 volumio volumio[776]: info: CorePlayQueue::clearPlayQueue May 15 02:27:15 volumio volumio[776]: info: CorePlayQueue::saveQueue May 15 02:27:15 volumio volumio[776]: info: CoreCommandRouter::volumioPushState May 15 02:27:15 volumio volumio[776]: info: CoreCommandRouter::volumioPushQueue May 15 02:27:15 volumio volumio[776]: info: CoreStateMachine::addQueueItems May 15 02:27:15 volumio volumio[776]: info: CorePlayQueue::addQueueItems May 15 02:27:15 volumio volumio[776]: info: Adding Item to queue: webkbs/0 May 15 02:27:15 volumio volumio[776]: info: Exploding uri webkbs/0 in service personal_radio May 15 02:27:15 volumio volumio[776]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 02:27:15 volumio volumio[776]: ReferenceError: self is not defined May 15 02:27:15 volumio volumio[776]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) May 15 02:27:15 volumio volumio[776]: at /data/plugins/music_service/personal_radio/index.js:487:12 May 15 02:27:15 volumio volumio[776]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) May 15 02:27:15 volumio volumio[776]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) May 15 02:27:15 volumio volumio[776]: at Request.emit (events.js:315:20) May 15 02:27:15 volumio volumio[776]: at Request.init (/data/plugins/music_service/personal_radio/node_modules/request/request.js:233:17) May 15 02:27:15 volumio volumio[776]: at new Request (/data/plugins/music_service/personal_radio/node_modules/request/request.js:129:8) May 15 02:27:15 volumio volumio[776]: at Function.request (/data/plugins/music_service/personal_radio/node_modules/request/index.js:55:10) May 15 02:27:15 volumio volumio[776]: at Object.end (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:595:27) May 15 02:27:15 volumio volumio[776]: at ControllerPersonalRadio.getStreamUrl (/data/plugins/music_service/personal_radio/index.js:482:4) May 15 02:27:15 volumio volumio[776]: at ControllerPersonalRadio.explodeUri (/data/plugins/music_service/personal_radio/index.js:355:12) May 15 02:27:15 volumio volumio[776]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1324:16) May 15 02:27:15 volumio volumio[776]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) May 15 02:27:15 volumio volumio[776]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:243:25) May 15 02:27:15 volumio volumio[776]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:487:23) May 15 02:27:15 volumio volumio[776]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:457:17) May 15 02:27:15 volumio volumio[776]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 02:27:16 volumio sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-15 02:26 May 15 02:27:16 volumio sudo[1739]: 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="9eadfb08439e8c37bef9a38c0641d736c1391e0c" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="1cdccc4d006cc26abc6312edbeae58f3081200d4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Feb 2022 07:16:13 PM CET" VOLUMIO_VERSION="3.233" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="88d67d58bfcedcd908dd7b955a1fa5bd"