-- Logs begin at Fri 2024-03-29 13:05:17 UTC, end at Fri 2024-03-29 13:27:46 UTC. -- Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:01 volumio volumio[1018]: info: Mar 29 13:26:01 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:01 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:01 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:01 volumio volumio[1018]: info: sendMpdCommand status took 0 milliseconds Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:01 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:26:01 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:01 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:26:01 volumio volumio[1018]: info: Mar 29 13:26:01 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:01 volumio volumio[1018]: info: Mar 29 13:26:01 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:01 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:01 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:01 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:01 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:01 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:01 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:01 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"stream","artist":"01 swarmstation radio","album":null,"uri":"https://swarmstation.radioca.st/stream","trackType":"st/stream"} Mar 29 13:26:01 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:01 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:01 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:01 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:01 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:01 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:01 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:01 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:01 volumio volumio[1018]: info: ------------------------------ 475ms Mar 29 13:26:03 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:26:03 volumio volumio[1018]: info: sendMpdCommand status took 2052 milliseconds Mar 29 13:26:03 volumio volumio[1018]: info: sendMpdCommand status took 2052 milliseconds Mar 29 13:26:03 volumio volumio[1018]: info: sendMpdCommand status took 2051 milliseconds Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:03 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:03 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:03 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:03 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:03 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:03 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5111,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"stream","artist":"01 swarmstation radio","album":null,"uri":"https://swarmstation.radioca.st/stream","trackType":"st/stream"} Mar 29 13:26:03 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:03 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:03 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:03 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5111,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"stream","artist":"01 swarmstation radio","album":null,"uri":"https://swarmstation.radioca.st/stream","trackType":"st/stream"} Mar 29 13:26:03 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:03 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:03 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:03 volumio volumio[1018]: info: ------------------------------ 2199ms Mar 29 13:26:03 volumio volumio[1018]: info: ------------------------------ 2201ms Mar 29 13:26:04 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:06 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:06 volumio volumio[1018]: info: Adding Item to queue: webrp/0 Mar 29 13:26:06 volumio volumio[1018]: info: Exploding uri webrp/0 in service radio_paradise Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:06 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:06 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand stop took 39 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand stop took 34 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received swarmstation Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:06 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand clear took 1 milliseconds Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 29 13:26:06 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces state update: options Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:06 volumio volumio[1018]: info: [1711718766187] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Mar 29 13:26:06 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:06 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 0 milliseconds Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand play took 3 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: [1711718766192] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:06 volumio volumio[1018]: info: Mar 29 13:26:06 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:06 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:06 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:06 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:06 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:06 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:06 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:06 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:06 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:07 volumio volumio[1018]: info: [1711718767656] [RadioParadise] received new metadata: {"time":87,"artist":"Jimi Hendrix","title":"The Wind Cries Mary","album":"Are You Experienced","year":"1967","cover":"https://img.radioparadise.com/covers/l/B00FEDP65W.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00FEDP65W.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00FEDP65W.jpg"} Mar 29 13:26:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:07 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:07 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00FEDP65W.jpg","uri":"https://stream.radioparadise.com/flac","name":"The Wind Cries Mary","title":"The Wind Cries Mary","artist":"Jimi Hendrix","album":"Are You Experienced","streaming":true,"disableUiControls":true,"duration":92,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:07 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:07 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:07 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:07 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:07 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:07 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:07 volumio volumio[1018]: info: [1711718767659] [RadioParadise] setting new timer with duration of 92000 seconds. Mar 29 13:26:07 volumio volumio[1018]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:26:07 volumio volumio[1018]: info: CoreStateMachine::getQueue Mar 29 13:26:07 volumio volumio[1018]: info: CorePlayQueue::getQueue Mar 29 13:26:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 1 Mar 29 13:26:08 volumio volumio[1018]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:12 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:12 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:12 volumio volumio[1018]: info: Adding Item to queue: webrp/1 Mar 29 13:26:12 volumio volumio[1018]: info: Exploding uri webrp/1 in service radio_paradise Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:12 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:12 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand stop took 46 milliseconds Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00FEDP65W.jpg","uri":"https://stream.radioparadise.com/flac","name":"The Wind Cries Mary","title":"The Wind Cries Mary","artist":"Jimi Hendrix","album":"Are You Experienced","streaming":true,"disableUiControls":true,"duration":92,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:12 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:12 volumio volumio[1018]: info: No code Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:12 volumio volumio[1018]: info: Mar 29 13:26:12 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:12 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand stop took 49 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:12 volumio volumio[1018]: info: Mar 29 13:26:12 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:12 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand clear took 4 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 29 13:26:12 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:12 volumio volumio[1018]: info: ------------------------------ 1ms Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand consume 1 took 0 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:12 volumio volumio[1018]: info: [1711718772132] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Mar 29 13:26:12 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:12 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:12 volumio volumio[1018]: info: Mar 29 13:26:12 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:12 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 0 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:12 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand play took 1 milliseconds Mar 29 13:26:12 volumio volumio[1018]: info: [1711718772134] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 29 13:26:12 volumio volumio[1018]: info: Mar 29 13:26:12 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:12 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:12 volumio volumio[1018]: info: Mar 29 13:26:12 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:12 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:12 volumio volumio[1018]: info: Mar 29 13:26:12 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:12 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:12 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:12 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:12 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:12 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:12 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:12 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:12 volumio volumio[1018]: info: ------------------------------ 5ms Mar 29 13:26:12 volumio volumio[1018]: info: [1711718772721] [RadioParadise] received new metadata: {"time":207,"artist":"The Innocence Mission","title":"Clear to You","album":"The Innocence Mission","year":"1989","cover":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002GIO.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002GIO.jpg"} Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Clear to You","title":"Clear to You","artist":"The Innocence Mission","album":"The Innocence Mission","streaming":true,"disableUiControls":true,"duration":212,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:12 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:12 volumio volumio[1018]: info: [1711718772724] [RadioParadise] setting new timer with duration of 212000 seconds. Mar 29 13:26:12 volumio volumio[1018]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:26:12 volumio volumio[1018]: info: CoreStateMachine::getQueue Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getQueue Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 1 Mar 29 13:26:16 volumio volumio[1018]: info: CoreCommandRouter::volumioPause Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::pause Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::servicePause Mar 29 13:26:16 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:16 volumio volumio[1018]: info: CoreCommandRouter::servicePause Mar 29 13:26:16 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 13:26:16 volumio volumio[1018]: info: sendMpdCommand pause took 81 milliseconds Mar 29 13:26:16 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:16 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:16 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:16 volumio volumio[1018]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Clear to You","title":"Clear to You","artist":"The Innocence Mission","album":"The Innocence Mission","streaming":true,"disableUiControls":true,"duration":212,"seek":3605,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:16 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::syncState stateService pause Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus pause Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:16 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:16 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:16 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:16 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:16 volumio volumio[1018]: info: Mar 29 13:26:16 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:16 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:16 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:16 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:16 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:16 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:16 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:16 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:16 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:16 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:16 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:16 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:16 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:17 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:17 volumio volumio[1018]: info: Mar 29 13:26:17 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:17 volumio volumio[1018]: info: sendMpdCommand play took 2 milliseconds Mar 29 13:26:17 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:17 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Clear to You","title":"Clear to You","artist":"The Innocence Mission","album":"The Innocence Mission","streaming":true,"disableUiControls":true,"duration":212,"seek":3605,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:17 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus pause Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:17 volumio volumio[1018]: info: [1711718777068] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 29 13:26:17 volumio volumio[1018]: info: sendMpdCommand status took 7 milliseconds Mar 29 13:26:17 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:17 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:17 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:17 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:17 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:17 volumio volumio[1018]: info: ------------------------------ 10ms Mar 29 13:26:17 volumio volumio[1018]: info: [1711718777660] [RadioParadise] received new metadata: {"time":202,"artist":"The Innocence Mission","title":"Clear to You","album":"The Innocence Mission","year":"1989","cover":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002GIO.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002GIO.jpg"} Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Clear to You","title":"Clear to You","artist":"The Innocence Mission","album":"The Innocence Mission","streaming":true,"disableUiControls":true,"duration":207,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:17 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:17 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:17 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:17 volumio volumio[1018]: info: [1711718777665] [RadioParadise] setting new timer with duration of 207000 seconds. Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 1 Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:19 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:19 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:19 volumio volumio[1018]: info: Adding Item to queue: webrp/2 Mar 29 13:26:19 volumio volumio[1018]: info: Exploding uri webrp/2 in service radio_paradise Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:19 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:19 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:19 volumio volumio[1018]: info: Mar 29 13:26:19 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand stop took 126 milliseconds Mar 29 13:26:19 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand stop took 119 milliseconds Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002GIO.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Clear to You","title":"Clear to You","artist":"The Innocence Mission","album":"The Innocence Mission","streaming":true,"disableUiControls":true,"duration":207,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:19 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:19 volumio volumio[1018]: info: No code Mar 29 13:26:19 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:19 volumio volumio[1018]: info: Mar 29 13:26:19 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:19 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand status took 5 milliseconds Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand clear took 3 milliseconds Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 29 13:26:19 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:19 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:19 volumio volumio[1018]: info: [1711718779719] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Mar 29 13:26:19 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:19 volumio volumio[1018]: info: ------------------------------ 10ms Mar 29 13:26:19 volumio volumio[1018]: info: Mar 29 13:26:19 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:19 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 0 milliseconds Mar 29 13:26:19 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:19 volumio volumio[1018]: info: ------------------------------ 1ms Mar 29 13:26:19 volumio volumio[1018]: info: sendMpdCommand play took 1 milliseconds Mar 29 13:26:19 volumio volumio[1018]: info: [1711718779722] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Mar 29 13:26:20 volumio volumio[1018]: info: Mar 29 13:26:20 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:20 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:20 volumio volumio[1018]: info: Mar 29 13:26:20 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:20 volumio volumio[1018]: info: Mar 29 13:26:20 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:20 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:20 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:20 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:20 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:20 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:20 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:20 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:20 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:20 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:20 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:20 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:20 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:20 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:20 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:20 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:20 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:20 volumio volumio[1018]: info: [1711718780312] [RadioParadise] received new metadata: {"time":222,"artist":"Dire Straits","title":"Expresso Love","album":"Making Movies","year":"1980","cover":"https://img.radioparadise.com/covers/l/B00004Y6NX.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00004Y6NX.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00004Y6NX.jpg"} Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:20 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:20 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00004Y6NX.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Expresso Love","title":"Expresso Love","artist":"Dire Straits","album":"Making Movies","streaming":true,"disableUiControls":true,"duration":227,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:20 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:20 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:20 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:20 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:20 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:20 volumio volumio[1018]: info: [1711718780315] [RadioParadise] setting new timer with duration of 227000 seconds. Mar 29 13:26:20 volumio volumio[1018]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:26:20 volumio volumio[1018]: info: CoreStateMachine::getQueue Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getQueue Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 1 Mar 29 13:26:23 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:23 volumio volumio[1018]: info: Mar 29 13:26:23 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:23 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:23 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:23 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:23 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:23 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:24 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:24 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:26:24 volumio volumio[1018]: info: Mar 29 13:26:24 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:24 volumio volumio[1018]: info: Mar 29 13:26:24 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:24 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:24 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:24 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:24 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:24 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:24 volumio volumio[1018]: info: ------------------------------ 607ms Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:24 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:24 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:24 volumio volumio[1018]: info: Adding Item to queue: webrp/3 Mar 29 13:26:24 volumio volumio[1018]: info: Exploding uri webrp/3 in service radio_paradise Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:24 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:24 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:24 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:24 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:24 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:24 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:26 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:26:26 volumio volumio[1018]: info: Mar 29 13:26:26 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand status took 2086 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand status took 2086 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand status took 2086 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand stop took 1964 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand stop took 1958 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:26 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:26 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:26 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:26 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:26 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00004Y6NX.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Expresso Love","title":"Expresso Love","artist":"Dire Straits","album":"Making Movies","streaming":true,"disableUiControls":true,"duration":227,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:26 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:26 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:26:26 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:26 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:26 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:26 volumio volumio[1018]: info: No code Mar 29 13:26:26 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:26 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:26 volumio volumio[1018]: info: Mar 29 13:26:26 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:26 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand status took 350 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 173 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 173 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand clear took 169 milliseconds Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 29 13:26:26 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:26 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:26 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:26 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:26 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:26 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:26 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:26 volumio volumio[1018]: info: ------------------------------ 2440ms Mar 29 13:26:26 volumio volumio[1018]: info: ------------------------------ 2439ms Mar 29 13:26:26 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:26 volumio volumio[1018]: info: ------------------------------ 9ms Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand consume 1 took 7 milliseconds Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:26 volumio volumio[1018]: info: [1711718786931] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/world-etc-flac Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" Mar 29 13:26:26 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:26 volumio volumio[1018]: info: ------------------------------ 360ms Mar 29 13:26:26 volumio volumio[1018]: info: Mar 29 13:26:26 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:26 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" took 0 milliseconds Mar 29 13:26:26 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:26 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:26 volumio volumio[1018]: info: sendMpdCommand play took 1 milliseconds Mar 29 13:26:26 volumio volumio[1018]: info: [1711718786938] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Mar 29 13:26:27 volumio volumio[1018]: info: Mar 29 13:26:27 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:27 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:27 volumio volumio[1018]: info: Mar 29 13:26:27 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:27 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:27 volumio volumio[1018]: info: Mar 29 13:26:27 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:27 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:27 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:27 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:26:27 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:27 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:27 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:27 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:27 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:27 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:27 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:27 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:27 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:27 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:27 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 29 13:26:27 volumio volumio[1018]: info: ------------------------------ 5ms Mar 29 13:26:27 volumio volumio[1018]: info: [1711718787540] [RadioParadise] received new metadata: {"time":98,"artist":"Buena Vista Social Club","title":"El cuarto de Tula","album":"Buena Vista Social Club","year":"1997","cover":"https://img.radioparadise.com/covers/l/B000005J56.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000005J56.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000005J56.jpg"} Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:27 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:27 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005J56.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"El cuarto de Tula","title":"El cuarto de Tula","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","streaming":true,"disableUiControls":true,"duration":103,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:27 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:27 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:27 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:27 volumio volumio[1018]: info: [1711718787543] [RadioParadise] setting new timer with duration of 103000 seconds. Mar 29 13:26:27 volumio volumio[1018]: info: CoreCommandRouter::volumioGetQueue Mar 29 13:26:27 volumio volumio[1018]: info: CoreStateMachine::getQueue Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getQueue Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 1 Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:34 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:34 volumio volumio[1018]: info: Adding Item to queue: webrp/4 Mar 29 13:26:34 volumio volumio[1018]: info: Exploding uri webrp/4 in service radio_paradise Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:34 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:34 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand stop took 56 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand stop took 49 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005J56.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"El cuarto de Tula","title":"El cuarto de Tula","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","streaming":true,"disableUiControls":true,"duration":103,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:34 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: No code Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:34 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand status took 6 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand clear took 4 milliseconds Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" Mar 29 13:26:34 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:34 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" took 3 milliseconds Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:34 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 12ms Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand play took 1 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:34 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:34 volumio volumio[1018]: info: Mar 29 13:26:34 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 29 13:26:34 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"391 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 29 13:26:34 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:34 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 10ms Mar 29 13:26:34 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 13:26:34 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:34 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:34 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:34 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"324 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 29 13:26:34 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:34 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:34 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:34 volumio volumio[1018]: info: ------------------------------ 17ms Mar 29 13:26:38 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:38 volumio volumio[1018]: info: Mar 29 13:26:38 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:38 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:38 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:38 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:38 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:38 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:38 volumio smbd[2946]: [2024/03/29 13:26:38.892345, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 29 13:26:38 volumio smbd[2946]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 29 13:26:39 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:26:39 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:39 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:39 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:39 volumio volumio[1018]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 29 13:26:39 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:26:39 volumio volumio[1018]: info: Mar 29 13:26:39 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:26:39 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:39 volumio volumio[1018]: info: Mar 29 13:26:39 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:39 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:39 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:39 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:39 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:39 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 13:26:39 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:39 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:39 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:39 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:39 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4458,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"321 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 29 13:26:39 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:39 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:39 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:39 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:39 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:39 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:39 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:39 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:39 volumio volumio[1018]: info: ------------------------------ 485ms Mar 29 13:26:40 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:26:40 volumio volumio[1018]: info: sendMpdCommand status took 1703 milliseconds Mar 29 13:26:40 volumio volumio[1018]: info: sendMpdCommand status took 1703 milliseconds Mar 29 13:26:40 volumio volumio[1018]: info: sendMpdCommand status took 1703 milliseconds Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:40 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:40 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:40 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:40 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:40 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:40 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:40 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 29 13:26:40 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:40 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:40 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:40 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:40 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 29 13:26:40 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:40 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:40 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:40 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:40 volumio volumio[1018]: info: ------------------------------ 1874ms Mar 29 13:26:40 volumio volumio[1018]: info: ------------------------------ 1873ms Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:41 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:41 volumio volumio[1018]: info: Adding Item to queue: webrp/5 Mar 29 13:26:41 volumio volumio[1018]: info: Exploding uri webrp/5 in service radio_paradise Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:41 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:41 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand stop took 126 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005J56.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"El cuarto de Tula","title":"El cuarto de Tula","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","streaming":true,"disableUiControls":true,"duration":103,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:41 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: No code Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand stop took 126 milliseconds Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:41 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand status took 3 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand clear took 3 milliseconds Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-128" Mar 29 13:26:41 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:41 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 1ms Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-128" took 1 milliseconds Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:41 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand play took 0 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:41 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:41 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:41 volumio volumio[1018]: info: Mar 29 13:26:41 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 1ms Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 6ms Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand status took 6 milliseconds Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"154 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":""} Mar 29 13:26:41 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 12ms Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 13:26:41 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:41 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"124 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":""} Mar 29 13:26:41 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:41 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:41 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:41 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":""} Mar 29 13:26:41 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:41 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 18ms Mar 29 13:26:41 volumio volumio[1018]: info: ------------------------------ 17ms Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:44 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:26:44 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:26:44 volumio volumio[1018]: info: Adding Item to queue: webrp/6 Mar 29 13:26:44 volumio volumio[1018]: info: Exploding uri webrp/6 in service radio_paradise Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:26:44 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:44 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:26:44 volumio volumio[1018]: info: Mar 29 13:26:44 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand stop took 95 milliseconds Mar 29 13:26:44 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand stop took 89 milliseconds Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005J56.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"El cuarto de Tula","title":"El cuarto de Tula","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","streaming":true,"disableUiControls":true,"duration":103,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 13:26:44 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:44 volumio volumio[1018]: info: No code Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:26:44 volumio volumio[1018]: info: Mar 29 13:26:44 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:44 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand status took 7 milliseconds Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand clear took 4 milliseconds Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-64" Mar 29 13:26:44 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:26:44 volumio volumio[1018]: info: Mar 29 13:26:44 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:44 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:44 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-64" took 3 milliseconds Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:44 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:26:44 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:26:44 volumio volumio[1018]: info: ------------------------------ 14ms Mar 29 13:26:44 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:44 volumio volumio[1018]: info: sendMpdCommand play took 1 milliseconds Mar 29 13:26:44 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 13:26:45 volumio volumio[1018]: info: Mar 29 13:26:45 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:45 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:45 volumio volumio[1018]: info: Mar 29 13:26:45 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:45 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:45 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:45 volumio volumio[1018]: info: Mar 29 13:26:45 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:45 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:45 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:45 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:45 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:45 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"59 Kbps","isStreaming":false,"title":"aac-64","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:45 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:45 volumio volumio[1018]: info: ------------------------------ 7ms Mar 29 13:26:45 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:26:45 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:45 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:45 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"aac-64","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:45 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:45 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:45 volumio volumio[1018]: info: ------------------------------ 10ms Mar 29 13:26:45 volumio volumio[1018]: info: Mar 29 13:26:45 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:26:45 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:26:45 volumio volumio[1018]: info: Mar 29 13:26:45 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:26:45 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:45 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:26:45 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:45 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:45 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:45 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:45 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"62 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:45 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:45 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:45 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:45 volumio volumio[1018]: info: ------------------------------ 5ms Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:49 volumio volumio[1018]: info: Mar 29 13:26:49 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:49 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:49 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:49 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:49 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:26:49 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:49 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:26:49 volumio volumio[1018]: info: Mar 29 13:26:49 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:49 volumio volumio[1018]: info: Mar 29 13:26:49 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:49 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:49 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:49 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:49 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:49 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:49 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:49 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:49 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4713,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:49 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:49 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:49 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:49 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:49 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:49 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:49 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:49 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:49 volumio volumio[1018]: info: ------------------------------ 570ms Mar 29 13:26:51 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:26:52 volumio volumio[1018]: info: sendMpdCommand status took 2156 milliseconds Mar 29 13:26:52 volumio volumio[1018]: info: sendMpdCommand status took 2156 milliseconds Mar 29 13:26:52 volumio volumio[1018]: info: sendMpdCommand status took 2156 milliseconds Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:52 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:52 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:52 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:52 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:52 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:52 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:52 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5596,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"60 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:52 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:52 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:52 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:52 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:52 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5596,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"60 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:52 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:52 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:52 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:52 volumio volumio[1018]: info: ------------------------------ 2395ms Mar 29 13:26:52 volumio volumio[1018]: info: ------------------------------ 2394ms Mar 29 13:26:52 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:57 volumio volumio[1018]: info: Mar 29 13:26:57 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:57 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:57 volumio volumio[1018]: info: sendMpdCommand status took 0 milliseconds Mar 29 13:26:57 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:57 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:26:57 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:57 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:57 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:57 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:57 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:57 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12469,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"74 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:57 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:57 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:57 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:57 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:57 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:57 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:57 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:57 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:57 volumio volumio[1018]: info: ------------------------------ 285ms Mar 29 13:26:57 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:26:57 volumio volumio[1018]: info: Mar 29 13:26:57 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:57 volumio volumio[1018]: info: Mar 29 13:26:57 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:26:57 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:57 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:26:57 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:26:58 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:26:59 volumio volumio[1018]: info: sendMpdCommand status took 1743 milliseconds Mar 29 13:26:59 volumio volumio[1018]: info: sendMpdCommand status took 1742 milliseconds Mar 29 13:26:59 volumio volumio[1018]: info: sendMpdCommand status took 1742 milliseconds Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:59 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:26:59 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:26:59 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:59 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:26:59 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:59 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:59 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12956,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:59 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:59 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:59 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:26:59 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:26:59 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12956,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Jimi Hendrix - The Wind Cries Mary","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Mar 29 13:26:59 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:26:59 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:59 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:26:59 volumio volumio[1018]: info: ------------------------------ 1910ms Mar 29 13:26:59 volumio volumio[1018]: info: ------------------------------ 1911ms Mar 29 13:26:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Mar 29 13:26:59 volumio volumio[1018]: info: [1711718819394] [rad357pl] handleBrowseUri curUri: rad357pl Mar 29 13:26:59 volumio volumio[1018]: info: [1711718819395] [rad357pl] getRadioContent url: rad357pl Mar 29 13:27:00 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:27:00 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:27:00 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:00 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:00 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:00 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:27:00 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:27:01 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:27:01 volumio volumio[1018]: info: Adding Item to queue: webrns/0 Mar 29 13:27:01 volumio volumio[1018]: info: Exploding uri webrns/0 in service rad357pl Mar 29 13:27:01 volumio volumio[1018]: info: [1711718821002] [rad357pl] explodeUri: webrns/0 Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:27:01 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:01 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:27:01 volumio volumio[1018]: info: Mar 29 13:27:01 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand stop took 85 milliseconds Mar 29 13:27:01 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand stop took 78 milliseconds Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:01 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:01 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:01 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received radio_paradise Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:27:01 volumio volumio[1018]: info: Mar 29 13:27:01 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:01 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand status took 3 milliseconds Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand clear took 1 milliseconds Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Mar 29 13:27:01 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:27:01 volumio volumio[1018]: info: Mar 29 13:27:01 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:01 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:01 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 2 milliseconds Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:01 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:27:01 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:27:01 volumio volumio[1018]: info: ------------------------------ 7ms Mar 29 13:27:01 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:27:01 volumio volumio[1018]: info: sendMpdCommand play took 1 milliseconds Mar 29 13:27:01 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 13:27:07 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:07 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:07 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:07 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:07 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:27:07 volumio volumio[1018]: info: Adding Item to queue: webrns/0 Mar 29 13:27:07 volumio volumio[1018]: info: Exploding uri webrns/0 in service rad357pl Mar 29 13:27:07 volumio volumio[1018]: info: [1711718827464] [rad357pl] explodeUri: webrns/0 Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:07 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:27:07 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:27:07 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::play index 0 Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:07 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:27:07 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:27:07 volumio volumio[1018]: info: Mar 29 13:27:07 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:27:07 volumio volumio[1018]: info: sendMpdCommand stop took 3 milliseconds Mar 29 13:27:07 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:27:07 volumio volumio[1018]: info: Mar 29 13:27:07 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:07 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:07 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:27:07 volumio volumio[1018]: info: sendMpdCommand clear took 1 milliseconds Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Mar 29 13:27:07 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:27:07 volumio volumio[1018]: info: Mar 29 13:27:07 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:07 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:07 volumio volumio[1018]: info: ------------------------------ 1ms Mar 29 13:27:07 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:27:07 volumio volumio[1018]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 1 milliseconds Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:07 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:27:07 volumio volumio[1018]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 13:27:07 volumio volumio[1018]: info: ------------------------------ 4ms Mar 29 13:27:07 volumio volumio[1018]: info: ------------------------------ 2ms Mar 29 13:27:07 volumio volumio[1018]: info: sendMpdCommand play took 0 milliseconds Mar 29 13:27:07 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 13:27:08 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:08 volumio volumio[1018]: info: Mar 29 13:27:08 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:27:08 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:08 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:08 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:27:08 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:27:08 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:09 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:27:09 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:27:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:09 volumio volumio[1018]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 29 13:27:09 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:27:09 volumio volumio[1018]: info: Mar 29 13:27:09 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:27:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:09 volumio volumio[1018]: info: Mar 29 13:27:09 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:27:09 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:09 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:09 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 13:27:09 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:09 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:09 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:09 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:09 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:09 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:09 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:09 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:27:09 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:09 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:09 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:09 volumio volumio[1018]: info: ------------------------------ 484ms Mar 29 13:27:11 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:27:11 volumio volumio[1018]: info: sendMpdCommand status took 2106 milliseconds Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:11 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:27:11 volumio volumio[1018]: info: sendMpdCommand status took 2276 milliseconds Mar 29 13:27:11 volumio volumio[1018]: info: sendMpdCommand status took 2276 milliseconds Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:11 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:27:11 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:11 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:11 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:11 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:11 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:11 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:11 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:11 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:11 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:11 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:11 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:11 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:11 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:11 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:11 volumio volumio[1018]: info: ------------------------------ 2285ms Mar 29 13:27:11 volumio volumio[1018]: info: ------------------------------ 2285ms Mar 29 13:27:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Mar 29 13:27:16 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Mar 29 13:27:17 volumio volumio[1018]: info: Mar 29 13:27:17 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:27:17 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:17 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:17 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 13:27:17 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:27:17 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:17 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:17 volumio volumio[1018]: info: sendMpdCommand clearerror took 1 milliseconds Mar 29 13:27:17 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:27:17 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:17 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:17 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:17 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:17 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:17 volumio volumio[1018]: 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":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:17 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:17 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:27:17 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:17 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:17 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:17 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:27:17 volumio volumio[1018]: info: ------------------------------ 8ms Mar 29 13:27:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Mar 29 13:27:21 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:21 volumio volumio[1018]: info: Mar 29 13:27:21 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:27:21 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:21 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:21 volumio volumio[1018]: info: sendMpdCommand status took 2 milliseconds Mar 29 13:27:21 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Mar 29 13:27:21 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:22 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:27:22 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:22 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:22 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:27:22 volumio volumio[1018]: info: Mar 29 13:27:22 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:27:22 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:22 volumio volumio[1018]: info: Mar 29 13:27:22 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:27:22 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:22 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:22 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:22 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:22 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 13:27:22 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:22 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:22 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:22 volumio volumio[1018]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:22 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:22 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:27:22 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:27:22 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:22 volumio volumio[1018]: info: No code Mar 29 13:27:22 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:22 volumio volumio[1018]: info: ------------------------------ 484ms Mar 29 13:27:27 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:27:27 volumio volumio[1018]: info: sendMpdCommand status took 5381 milliseconds Mar 29 13:27:27 volumio volumio[1018]: info: sendMpdCommand status took 5381 milliseconds Mar 29 13:27:27 volumio volumio[1018]: info: sendMpdCommand status took 5381 milliseconds Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:27 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:27 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:27:27 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:27 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:27 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:27 volumio volumio[1018]: 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":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:27 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:27 volumio volumio[1018]: info: No code Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:27 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:27 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Mar 29 13:27:27 volumio volumio[1018]: 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":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Mar 29 13:27:27 volumio volumio[1018]: verbose: CURRENT POSITION 0 Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:27 volumio volumio[1018]: info: No code Mar 29 13:27:27 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:27 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:27 volumio volumio[1018]: info: ------------------------------ 5688ms Mar 29 13:27:27 volumio volumio[1018]: info: ------------------------------ 5689ms Mar 29 13:27:30 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:27:30 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:27:30 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:30 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:30 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:30 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:27:30 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:30 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:30 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:30 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:30 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:27:30 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/0 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/0 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/1 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/1 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/2 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/2 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/3 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/3 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/4 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/4 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/5 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/5 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/6 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/6 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/7 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/7 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/8 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/8 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/9 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/9 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/10 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/10 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/11 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/11 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/12 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/12 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/13 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/13 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/14 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/14 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/15 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/15 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/16 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/16 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/17 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/17 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/18 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/18 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/19 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/19 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/20 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/20 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/21 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/21 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/22 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/22 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/23 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/23 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/24 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/24 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/25 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/25 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/26 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/26 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/27 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/27 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/28 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/28 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/29 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/29 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/30 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/30 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/31 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/31 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/32 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/32 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/33 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/33 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/34 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/34 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/35 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/35 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/36 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/36 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/37 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/37 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/38 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/38 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/39 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/39 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/40 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/40 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/41 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/41 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/42 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/42 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/43 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/43 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/44 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/44 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/45 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/45 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/46 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/46 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/47 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/47 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/48 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/48 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/49 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/49 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/50 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/50 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/51 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/51 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/52 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/52 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/53 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/53 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/54 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/54 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/55 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/55 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/56 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/56 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/57 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/57 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/58 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/58 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/59 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/59 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/60 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/60 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/61 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/61 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/62 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/62 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/63 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/63 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/64 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/64 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/65 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/65 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/66 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/66 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/67 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/67 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/68 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/68 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/69 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/69 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/70 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/70 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/71 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/71 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/72 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/72 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/73 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/73 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/74 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/74 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/75 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/75 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/76 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/76 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/77 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/77 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/78 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/78 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/79 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/79 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/80 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/80 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/81 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/81 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/82 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/82 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/83 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/83 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/84 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/84 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/85 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/85 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/86 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/86 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/87 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/87 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/88 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/88 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/89 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/89 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/90 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/90 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/91 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/91 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/92 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/92 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/93 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/93 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/94 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/94 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/95 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/95 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/96 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/96 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/97 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/97 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/98 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/98 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/99 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/99 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/100 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/100 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/101 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/101 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/102 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/102 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/103 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/103 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/104 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/104 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/105 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/105 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/106 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/106 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/107 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/107 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/108 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/108 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/109 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/109 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/110 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/110 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/111 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/111 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/112 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/112 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/113 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/113 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/114 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/114 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/115 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/115 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/116 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/116 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/117 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/117 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/118 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/118 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/119 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/119 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/120 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/120 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/121 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/121 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/122 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/122 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/123 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/123 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/124 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/124 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/125 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/125 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/126 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/126 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/127 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/127 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/128 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/128 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/129 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/129 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/130 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/130 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/131 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/131 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/132 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/132 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/133 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/133 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/134 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/134 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/135 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/135 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/136 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/136 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/137 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/137 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/138 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/138 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/139 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/139 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/140 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/140 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/141 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/141 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/142 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/142 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/143 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/143 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/144 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/144 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/145 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/145 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/146 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/146 in service podcast Mar 29 13:27:30 volumio volumio[1018]: info: Adding Item to queue: podcast/1/147 Mar 29 13:27:30 volumio volumio[1018]: info: Exploding uri podcast/1/147 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/148 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/148 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/149 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/149 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/150 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/150 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/151 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/151 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/152 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/152 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/153 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/153 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/154 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/154 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/155 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/155 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/156 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/156 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/157 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/157 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/158 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/158 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/159 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/159 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/160 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/160 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/161 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/161 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/162 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/162 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/163 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/163 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/164 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/164 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/165 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/165 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/166 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/166 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/167 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/167 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/168 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/168 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/169 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/169 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/170 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/170 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/171 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/171 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/172 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/172 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/173 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/173 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/174 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/174 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/175 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/175 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/176 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/176 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/177 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/177 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/178 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/178 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/179 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/179 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/180 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/180 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/181 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/181 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/182 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/182 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/183 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/183 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/184 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/184 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/185 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/185 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/186 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/186 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/187 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/187 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/188 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/188 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/189 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/189 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/190 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/190 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/191 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/191 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/192 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/192 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/193 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/193 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/194 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/194 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/195 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/195 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/196 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/196 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/197 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/197 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/198 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/198 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/199 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/199 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/200 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/200 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/201 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/201 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/202 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/202 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/203 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/203 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/204 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/204 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/205 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/205 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/206 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/206 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/207 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/207 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/208 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/208 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/209 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/209 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/210 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/210 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/211 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/211 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/212 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/212 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/213 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/213 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/214 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/214 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/215 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/215 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/216 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/216 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/217 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/217 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/218 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/218 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/219 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/219 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/220 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/220 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/221 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/221 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/222 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/222 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/223 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/223 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/224 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/224 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/225 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/225 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/226 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/226 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/227 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/227 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/228 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/228 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/229 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/229 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/230 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/230 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/231 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/231 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/232 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/232 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/233 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/233 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/234 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/234 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/235 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/235 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/236 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/236 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/237 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/237 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/238 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/238 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/239 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/239 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/240 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/240 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/241 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/241 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/242 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/242 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/243 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/243 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/244 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/244 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/245 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/245 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/246 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/246 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/247 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/247 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/248 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/248 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/249 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/249 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/250 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/250 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/251 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/251 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/252 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/252 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/253 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/253 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/254 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/254 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/255 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/255 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/256 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/256 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/257 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/257 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/258 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/258 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/259 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/259 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/260 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/260 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/261 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/261 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/262 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/262 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/263 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/263 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/264 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/264 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/265 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/265 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/266 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/266 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/267 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/267 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/268 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/268 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/269 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/269 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/270 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/270 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/271 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/271 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/272 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/272 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/273 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/273 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/274 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/274 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/275 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/275 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/276 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/276 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/277 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/277 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/278 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/278 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/279 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/279 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/280 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/280 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/281 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/281 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/282 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/282 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/283 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/283 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/284 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/284 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/285 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/285 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/286 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/286 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/287 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/287 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/288 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/288 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/289 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/289 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/290 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/290 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/291 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/291 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/292 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/292 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/293 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/293 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/294 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/294 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/295 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/295 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/296 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/296 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/297 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/297 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/298 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/298 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/299 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/299 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: Adding Item to queue: podcast/1/300 Mar 29 13:27:31 volumio volumio[1018]: info: Exploding uri podcast/1/300 in service podcast Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:31 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:27:31 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Mar 29 13:27:31 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::play index 7 Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:31 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::play index undefined Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:31 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:27:31 volumio volumio[1018]: info: sendMpdCommand stop took 183 milliseconds Mar 29 13:27:31 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 13:27:31 volumio volumio[1018]: info: Mar 29 13:27:31 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:31 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:31 volumio volumio[1018]: info: sendMpdCommand clear took 2 milliseconds Mar 29 13:27:31 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl" Mar 29 13:27:31 volumio volumio[1018]: error: updateQueue error: null Mar 29 13:27:31 volumio volumio[1018]: info: Mar 29 13:27:31 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:31 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:31 volumio volumio[1018]: info: ------------------------------ 7ms Mar 29 13:27:31 volumio volumio[1018]: info: sendMpdCommand add "https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl" took 5 milliseconds Mar 29 13:27:31 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Mar 29 13:27:31 volumio volumio[1018]: info: ------------------------------ 3ms Mar 29 13:27:31 volumio volumio[1018]: info: sendMpdCommand play took 2 milliseconds Mar 29 13:27:31 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:31 volumio volumio[1018]: info: sendMpdCommand status took 0 milliseconds Mar 29 13:27:31 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:31 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:31 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"uri":"https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl","trackType":"Podcast","serviceName":"podcast"} Mar 29 13:27:31 volumio volumio[1018]: verbose: CURRENT POSITION 7 Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Mar 29 13:27:31 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:31 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:31 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:31 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: info: Mar 29 13:27:33 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:27:33 volumio volumio[1018]: info: sendMpdCommand status took 4 milliseconds Mar 29 13:27:33 volumio volumio[1018]: info: sendMpdCommand status took 3 milliseconds Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:33 volumio volumio[1018]: info: Command Router : Notfying DB Updatetrue Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:33 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:33 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:33 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"uri":"https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl","trackType":"Podcast","serviceName":"podcast"} Mar 29 13:27:33 volumio volumio[1018]: verbose: CURRENT POSITION 7 Mar 29 13:27:33 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:33 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:33 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:33 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:33 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:33 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:33 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:33 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:33 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:33 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:33 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:33 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 29 13:27:33 volumio volumio[1018]: info: ------------------------------ 287ms Mar 29 13:27:33 volumio volumio[1018]: info: MPD Database updated - AlbumList cache refreshed Mar 29 13:27:33 volumio volumio[1018]: info: Mar 29 13:27:33 volumio volumio[1018]: ---------------------------- MPD announces state update: database Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: info: Mar 29 13:27:33 volumio volumio[1018]: ---------------------------- MPD announces state update: update Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:33 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:33 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:35 volumio volumio[1018]: Cannot compose Albumart path Mar 29 13:27:35 volumio volumio[1018]: info: Mar 29 13:27:35 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 1728 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 1728 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 1728 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 1728 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 1727 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: info: Command Router : Notfying DB Updatefalse Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"uri":"https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl","trackType":"Podcast","serviceName":"podcast"} Mar 29 13:27:35 volumio volumio[1018]: verbose: CURRENT POSITION 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:35 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"uri":"https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl","trackType":"Podcast","serviceName":"podcast"} Mar 29 13:27:35 volumio volumio[1018]: verbose: CURRENT POSITION 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:35 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: Mar 29 13:27:35 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Mar 29 13:27:35 volumio volumio[1018]: info: Ignoring MPD Status Update Mar 29 13:27:35 volumio volumio[1018]: info: Mar 29 13:27:35 volumio volumio[1018]: ---------------------------- MPD announces state update: player Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:35 volumio volumio[1018]: info: ControllerMpd::getState Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 184 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 183 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1613,"duration":1699,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"uri":"https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl","trackType":"Podcast","serviceName":"podcast"} Mar 29 13:27:35 volumio volumio[1018]: verbose: CURRENT POSITION 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:35 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 29 13:27:35 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 29 13:27:35 volumio volumio[1018]: info: ------------------------------ 1919ms Mar 29 13:27:35 volumio volumio[1018]: info: ------------------------------ 1919ms Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 12 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: ------------------------------ 13ms Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand status took 12 milliseconds Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseState Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1857,"duration":1699,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"uri":"https://dts.podtrac.com/redirect.mp3/chrt.fm/track/8DB4DB/pdst.fm/e/pfx.vpixl.com/6qj4J/nyt.simplecastaudio.com/03d8b493-87fc-4bd1-931f-8a8e9b945d8a/episodes/2200e751-c236-4970-8a16-0da68f2b402e/audio/128/default.mp3?aid=rss_feed&awCollectionId=03d8b493-87fc-4bd1-931f-8a8e9b945d8a&awEpisodeId=2200e751-c236-4970-8a16-0da68f2b402e&feed=54nAGcIl","trackType":"Podcast","serviceName":"podcast"} Mar 29 13:27:35 volumio volumio[1018]: verbose: CURRENT POSITION 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Mar 29 13:27:35 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:35 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 29 13:27:35 volumio volumio[1018]: info: ------------------------------ 202ms Mar 29 13:27:35 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 13:27:35 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Mar 29 13:27:35 volumio volumio[1018]: info: ControllerMpd::pushState Mar 29 13:27:35 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Mar 29 13:27:35 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:35 volumio volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 29 13:27:35 volumio volumio[1018]: info: ------------------------------ 19ms Mar 29 13:27:39 volumio volumio[1018]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::ClearQueue Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::stop Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 13:27:46 volumio volumio[1018]: verbose: UNSET VOLATILE Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Mar 29 13:27:46 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::pushState Mar 29 13:27:46 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::serviceStop Mar 29 13:27:46 volumio volumio[1018]: info: CorePlayQueue::getTrack 7 Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Mar 29 13:27:46 volumio volumio[1018]: info: ControllerMpd::stop Mar 29 13:27:46 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 13:27:46 volumio volumio[1018]: info: CorePlayQueue::clearPlayQueue Mar 29 13:27:46 volumio volumio[1018]: info: CorePlayQueue::saveQueue Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 13:27:46 volumio volumio[1018]: info: CoreCommandRouter::volumioPushQueue Mar 29 13:27:46 volumio volumio[1018]: info: CoreStateMachine::addQueueItems Mar 29 13:27:46 volumio volumio[1018]: info: CorePlayQueue::addQueueItems Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/0 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/0 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/1 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/1 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/2 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/2 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/3 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/3 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/4 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/4 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/5 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/5 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/6 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/6 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/7 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/7 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/8 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/8 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/9 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/9 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/10 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/10 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/11 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/11 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/12 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/12 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/13 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/13 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/14 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/14 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/15 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/15 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/16 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/16 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/17 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/17 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/18 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/18 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/19 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/19 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/20 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/20 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/21 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/21 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/22 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/22 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/23 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/23 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/24 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/24 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/25 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/25 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/26 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/26 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/27 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/27 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/28 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/28 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/29 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/29 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/30 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/30 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/31 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/31 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/32 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/32 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/33 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/33 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/34 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/34 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/35 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/35 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/36 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/36 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/37 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/37 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/38 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/38 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/39 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/39 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/40 Mar 29 13:27:46 volumio volumio[1018]: info: Exploding uri podcast/1/40 in service podcast Mar 29 13:27:46 volumio volumio[1018]: info: Adding Item to queue: podcast/1/41 PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"