May 31 02:47:45 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 31 02:47:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:47:53 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 02:47:53 volumio volumio[1220]: info: Preload queue cleared May 31 02:47:55 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 02:47:55 volumio volumio[1220]: info: Preload queue cleared May 31 02:47:59 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 02:47:59 volumio volumio[1220]: info: READ May 31 02:47:59 volumio volumio[1220]: info: Preload queue cleared May 31 02:48:04 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 02:48:04 volumio volumio[1220]: info: Preload queue cleared May 31 02:48:09 volumio volumio[1220]: info: Preload queue cleared May 31 02:48:09 volumio volumio[1220]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::ClearQueue May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::stop May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::clearPlayQueue May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 31 02:48:09 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::addQueueItems May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::addQueueItems May 31 02:48:09 volumio volumio[1220]: info: Preload queue cleared May 31 02:48:09 volumio volumio[1220]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99587682 May 31 02:48:09 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 02:48:09 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 31 02:48:09 volumio volumio[1220]: info: CoreCommandRouter::volumioPlay May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::play index 0 May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::stop May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::play index undefined May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::startPlaybackTimer May 31 02:48:09 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:09 volumio volumio[1220]: info: [1780210089623] ControllerWebradio::clearAddPlayTrack May 31 02:48:09 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 31 02:48:09 volumio volumio[1220]: info: sendMpdCommand stop took 4 milliseconds May 31 02:48:09 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand clear May 31 02:48:09 volumio volumio[1220]: info: May 31 02:48:09 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:09 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:09 volumio volumio[1220]: info: sendMpdCommand clear took 3 milliseconds May 31 02:48:09 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99587682" May 31 02:48:09 volumio volumio[1220]: info: May 31 02:48:09 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:09 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:09 volumio volumio[1220]: info: May 31 02:48:09 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:09 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:09 volumio volumio[1220]: error: updateQueue error: null May 31 02:48:09 volumio volumio[1220]: info: ------------------------------ 5ms May 31 02:48:09 volumio volumio[1220]: info: May 31 02:48:09 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:09 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:09 volumio volumio[1220]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99587682" took 174 milliseconds May 31 02:48:09 volumio volumio[1220]: info: ------------------------------ 174ms May 31 02:48:09 volumio volumio[1220]: info: ------------------------------ 173ms May 31 02:48:09 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 02:48:09 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand play May 31 02:48:09 volumio volumio[1220]: info: May 31 02:48:09 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:09 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:09 volumio volumio[1220]: info: May 31 02:48:09 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:09 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:09 volumio volumio[1220]: info: ------------------------------ 7ms May 31 02:48:09 volumio volumio[1220]: info: sendMpdCommand play took 6 milliseconds May 31 02:48:09 volumio volumio[1220]: info: ------------------------------ 4ms May 31 02:48:09 volumio volumio[1220]: info: ------------------------------ 3ms May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 7 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 5 milliseconds May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 4 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 4 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 3 milliseconds May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 5 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus stop May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 38ms May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 72ms May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 72ms May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 71ms May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 71ms May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 62 milliseconds May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 89ms May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:11 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:11 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 31 02:48:11 volumio volumio[1220]: info: Ignoring MPD Status Update May 31 02:48:11 volumio volumio[1220]: info: May 31 02:48:11 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 6ms May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 5 milliseconds May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 4ms May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 4 milliseconds May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 5ms May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand status took 4 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jelly Roll - Kill A Man","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jelly Roll - Kill A Man","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 50ms May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 49ms May 31 02:48:11 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 40 milliseconds May 31 02:48:11 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:11 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:11 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:11 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jelly Roll - Kill A Man","artist":"Dirt Road Radio","album":null,"uri":"https://cheetah.streemlion.com:3030/","trackType":"com:3030/"} May 31 02:48:11 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 31 02:48:11 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:11 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:11 volumio volumio[1220]: info: ------------------------------ 65ms May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::volumioStop May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::stop May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::stPlaybackTimer May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::serviceStop May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::serviceStop May 31 02:48:20 volumio volumio[1220]: info: [1780210100246] ControllerWebradio::stop May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand stop took 37 milliseconds May 31 02:48:20 volumio volumio[1220]: info: May 31 02:48:20 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:20 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:20 volumio volumio[1220]: info: May 31 02:48:20 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:20 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:20 volumio volumio[1220]: info: May 31 02:48:20 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 31 02:48:20 volumio volumio[1220]: info: ControllerMpd::getState May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand status took 4 milliseconds May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand status took 3 milliseconds May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand status took 3 milliseconds May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::parseState May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 02:48:20 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:20 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 31 02:48:20 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 02:48:20 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 02:48:20 volumio volumio[1220]: info: ControllerMpd::pushState May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 31 02:48:20 volumio volumio[1220]: info: CoreStateMachine::pushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 31 02:48:20 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:20 volumio volumio[1220]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 02:48:20 volumio volumio[1220]: info: ------------------------------ 29ms May 31 02:48:20 volumio volumio[1220]: info: ------------------------------ 28ms May 31 02:48:20 volumio volumio[1220]: info: ------------------------------ 28ms May 31 02:48:23 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 31 02:48:23 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 31 02:48:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 02:48:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 02:48:32 volumio volumio[1220]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 02:48:32 volumio volumio[1220]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out May 31 02:48:32 volumio volumio[1220]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 31 02:48:32 volumio volumio[1220]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 02:48:32 volumio volumio[1220]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 31 02:48:32 volumio volumio[1220]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 31 02:48:32 volumio volumio[1220]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 31 02:48:32 volumio volumio[1220]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 31 02:48:32 volumio volumio[1220]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out May 31 02:48:32 volumio volumio[1220]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 31 02:48:32 volumio volumio[1220]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 02:48:32 volumio volumio[1220]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 31 02:48:32 volumio volumio[1220]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 31 02:48:32 volumio volumio[1220]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 31 02:48:32 volumio volumio[1220]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 31 02:48:43 volumio volumio[1220]: info: CALLMETHOD: music_service ytmusic configSaveI18n [object Object] May 31 02:48:43 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveI18n May 31 02:48:43 volumio volumio[1220]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 02:48:43 volumio volumio[1220]: Error: VM operation timed out May 31 02:48:43 volumio volumio[1220]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) May 31 02:48:43 volumio volumio[1220]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 02:48:43 volumio volumio[1220]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) May 31 02:48:43 volumio volumio[1220]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) May 31 02:48:43 volumio volumio[1220]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) May 31 02:48:43 volumio volumio[1220]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) May 31 02:48:43 volumio volumio[1220]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 02:48:44 volumio sudo[2741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 02:47' May 31 02:48:44 volumio sudo[2741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"