-- Logs begin at Tue 2022-06-28 15:12:22 UTC, end at Tue 2022-06-28 21:29:40 UTC. -- Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:02 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::serviceStop Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::serviceStop Jun 28 21:28:02 volumio volumio[968]: info: [1656451682537] ControllerWebradio::stop Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:28:02 volumio volumio[968]: info: Adding Item to queue: https://playerservices.streamtheworld.com/api/livestream-redirect/CBFFM_SRC.mp3 Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:02 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:28:02 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::play index 0 Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:02 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:28:02 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:02 volumio volumio[968]: info: [1656451682560] ControllerWebradio::clearAddPlayTrack Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:02 volumio volumio[968]: info: Jun 28 21:28:02 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand stop took 44 milliseconds Jun 28 21:28:02 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand stop took 21 milliseconds Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:28:02 volumio volumio[968]: info: Jun 28 21:28:02 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:02 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand clear took 4 milliseconds Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/CBFFM_SRC.mp3" Jun 28 21:28:02 volumio volumio[968]: error: updateQueue error: null Jun 28 21:28:02 volumio volumio[968]: info: ------------------------------ 7ms Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:02 volumio volumio[968]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 28 21:28:02 volumio volumio[968]: info: ------------------------------ 14ms Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/CBFFM_SRC.mp3" Jun 28 21:28:02 volumio volumio[968]: info: Jun 28 21:28:02 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:02 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/CBFFM_SRC.mp3" took 1 milliseconds Jun 28 21:28:02 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 21:28:02 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:28:02 volumio volumio[968]: info: ------------------------------ 3ms Jun 28 21:28:02 volumio volumio[968]: info: sendMpdCommand play took 2 milliseconds Jun 28 21:28:03 volumio volumio[968]: info: Jun 28 21:28:03 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:03 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:03 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:03 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:28:03 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:03 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:03 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:03 volumio volumio[968]: info: sendMpdCommand clearerror took 2 milliseconds Jun 28 21:28:03 volumio volumio[968]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 28 21:28:03 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:03 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:03 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:03 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:03 volumio volumio[968]: 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":"CBFFM_SRC.mp3","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/CBFFM_SRC.mp3","trackType":"mp3"} Jun 28 21:28:03 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:03 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Jun 28 21:28:03 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:03 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:03 volumio volumio[968]: info: No code Jun 28 21:28:03 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:03 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:03 volumio volumio[968]: info: ------------------------------ 29ms Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:06 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:28:06 volumio volumio[968]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/la-1ere/mp3_128 Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:28:06 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::play index 0 Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:06 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:06 volumio volumio[968]: info: [1656451686572] ControllerWebradio::clearAddPlayTrack Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand stop took 9 milliseconds Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:06 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand clear took 2 milliseconds Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/la-1ere/mp3_128" Jun 28 21:28:06 volumio volumio[968]: error: updateQueue error: null Jun 28 21:28:06 volumio volumio[968]: info: ------------------------------ 4ms Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/la-1ere/mp3_128" Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:06 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/la-1ere/mp3_128" took 2 milliseconds Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:28:06 volumio volumio[968]: info: ------------------------------ 4ms Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand play took 2 milliseconds Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:06 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:06 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:06 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:06 volumio volumio[968]: info: ------------------------------ 6ms Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:06 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:06 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":298,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mp3_128","artist":"/RTS1_Room1","album":null,"uri":"http://stream.srg-ssr.ch/m/la-1ere/mp3_128","trackType":""} Jun 28 21:28:06 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:06 volumio volumio[968]: info: ------------------------------ 20ms Jun 28 21:28:06 volumio volumio[968]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:06 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:06 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mp3_128","artist":"/RTS1_Room1","album":null,"uri":"http://stream.srg-ssr.ch/m/la-1ere/mp3_128","trackType":""} Jun 28 21:28:06 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus play Jun 28 21:28:06 volumio volumio[968]: info: Received an update from plugin. extracting info from payload Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:06 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:06 volumio volumio[968]: info: ------------------------------ 45ms Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:06 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:06 volumio volumio[968]: info: Jun 28 21:28:06 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:06 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:07 volumio volumio[968]: info: ------------------------------ 284ms Jun 28 21:28:07 volumio volumio[968]: info: sendMpdCommand status took 283 milliseconds Jun 28 21:28:07 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:07 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:07 volumio volumio[968]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 28 21:28:07 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:07 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:07 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:07 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:07 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Forum","artist":"/RTS1_Room1","album":null,"uri":"http://stream.srg-ssr.ch/m/la-1ere/mp3_128","trackType":""} Jun 28 21:28:07 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:07 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:07 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus play Jun 28 21:28:07 volumio volumio[968]: info: Received an update from plugin. extracting info from payload Jun 28 21:28:07 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 28 21:28:07 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:07 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:07 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:07 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:07 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:07 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:07 volumio volumio[968]: info: ------------------------------ 314ms Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:11 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::serviceStop Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::serviceStop Jun 28 21:28:11 volumio volumio[968]: info: [1656451691601] ControllerWebradio::stop Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:28:11 volumio volumio[968]: info: Adding Item to queue: http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:11 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:28:11 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::play index 0 Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:11 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:11 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:28:11 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:11 volumio volumio[968]: info: [1656451691620] ControllerWebradio::clearAddPlayTrack Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:11 volumio volumio[968]: info: Jun 28 21:28:11 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:11 volumio volumio[968]: info: sendMpdCommand stop took 73 milliseconds Jun 28 21:28:11 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:11 volumio volumio[968]: info: sendMpdCommand stop took 55 milliseconds Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:28:11 volumio volumio[968]: info: Jun 28 21:28:11 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:11 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:11 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:11 volumio volumio[968]: info: sendMpdCommand clear took 3 milliseconds Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand load "http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg" Jun 28 21:28:11 volumio volumio[968]: error: updateQueue error: null Jun 28 21:28:11 volumio volumio[968]: info: ------------------------------ 5ms Jun 28 21:28:11 volumio volumio[968]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 28 21:28:11 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:11 volumio volumio[968]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 28 21:28:11 volumio volumio[968]: info: ------------------------------ 11ms Jun 28 21:28:12 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg" Jun 28 21:28:12 volumio volumio[968]: info: Jun 28 21:28:12 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:12 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:12 volumio volumio[968]: info: sendMpdCommand add "http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg" took 2 milliseconds Jun 28 21:28:12 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 21:28:12 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:28:12 volumio volumio[968]: info: ------------------------------ 3ms Jun 28 21:28:12 volumio volumio[968]: info: sendMpdCommand play took 2 milliseconds Jun 28 21:28:13 volumio volumio[968]: info: Jun 28 21:28:13 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:13 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:13 volumio volumio[968]: info: Jun 28 21:28:13 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:13 volumio volumio[968]: info: Jun 28 21:28:13 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:13 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:13 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:13 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:13 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:13 volumio volumio[968]: info: ------------------------------ 6ms Jun 28 21:28:13 volumio volumio[968]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:13 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:13 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:13 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":336,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","artist":null,"album":null,"uri":"http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","trackType":""} Jun 28 21:28:13 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:13 volumio volumio[968]: info: ------------------------------ 25ms Jun 28 21:28:13 volumio volumio[968]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 28 21:28:13 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:13 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:13 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:13 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","artist":null,"album":null,"uri":"http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","trackType":""} Jun 28 21:28:13 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus play Jun 28 21:28:13 volumio volumio[968]: info: Received an update from plugin. extracting info from payload Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:13 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:13 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:13 volumio volumio[968]: info: ------------------------------ 40ms Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:14 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::serviceStop Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::serviceStop Jun 28 21:28:14 volumio volumio[968]: info: [1656451694120] ControllerWebradio::stop Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:28:14 volumio volumio[968]: info: Adding Item to queue: http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:14 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:28:14 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::play index 0 Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:14 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:14 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:28:14 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:14 volumio volumio[968]: info: [1656451694146] ControllerWebradio::clearAddPlayTrack Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:14 volumio volumio[968]: info: sendMpdCommand stop took 103 milliseconds Jun 28 21:28:14 volumio volumio[968]: info: Jun 28 21:28:14 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:14 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:14 volumio volumio[968]: info: sendMpdCommand stop took 79 milliseconds Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:28:14 volumio volumio[968]: info: Jun 28 21:28:14 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:14 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:14 volumio volumio[968]: info: sendMpdCommand status took 3 milliseconds Jun 28 21:28:14 volumio volumio[968]: info: sendMpdCommand clear took 2 milliseconds Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand load "http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg" Jun 28 21:28:14 volumio volumio[968]: error: updateQueue error: null Jun 28 21:28:14 volumio volumio[968]: info: ------------------------------ 5ms Jun 28 21:28:14 volumio volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 21:28:14 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:14 volumio volumio[968]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 28 21:28:14 volumio volumio[968]: info: ------------------------------ 9ms Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg" Jun 28 21:28:15 volumio volumio[968]: info: Jun 28 21:28:15 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:15 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:15 volumio volumio[968]: info: sendMpdCommand add "http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg" took 4 milliseconds Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:28:15 volumio volumio[968]: info: ------------------------------ 5ms Jun 28 21:28:15 volumio volumio[968]: info: sendMpdCommand play took 2 milliseconds Jun 28 21:28:15 volumio volumio[968]: info: Jun 28 21:28:15 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:15 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:15 volumio volumio[968]: info: Jun 28 21:28:15 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:15 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:15 volumio volumio[968]: info: Jun 28 21:28:15 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:15 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:15 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:28:15 volumio volumio[968]: info: ------------------------------ 4ms Jun 28 21:28:15 volumio volumio[968]: info: sendMpdCommand status took 4 milliseconds Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:15 volumio volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 21:28:15 volumio volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:15 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:15 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:15 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:15 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":255,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","artist":null,"album":null,"uri":"http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","trackType":""} Jun 28 21:28:15 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:15 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:15 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:15 volumio volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":290,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","artist":null,"album":null,"uri":"http://streaming.radio.rtl.fr/rtl-1-44-128?listen=webCwsBCggNCQgLDQUGBAcGBg","trackType":""} Jun 28 21:28:15 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus play Jun 28 21:28:15 volumio volumio[968]: info: Received an update from plugin. extracting info from payload Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:15 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:15 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:15 volumio volumio[968]: info: ------------------------------ 37ms Jun 28 21:28:15 volumio volumio[968]: info: ------------------------------ 34ms Jun 28 21:28:22 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio Jun 28 21:28:22 volumio volumio[968]: info: {"name":"Radio Quebec"} Jun 28 21:28:22 volumio volumio[968]: info: Saving local item my-web-radio Jun 28 21:28:22 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 28 21:28:27 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Jun 28 21:28:29 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:35 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::serviceStop Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::serviceStop Jun 28 21:28:35 volumio volumio[968]: info: [1656451715847] ControllerWebradio::stop Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/0 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/0 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/1 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/1 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/2 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/2 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/3 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/3 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/4 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/4 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/5 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/5 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/6 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/6 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/7 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/7 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/8 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/8 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/9 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/9 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/10 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/10 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/11 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/11 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/12 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/12 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: Adding Item to queue: podcast/20/13 Jun 28 21:28:35 volumio volumio[968]: info: Exploding uri podcast/20/13 in service podcast Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:28:35 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::play index 1 Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:35 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand stop took 76 milliseconds Jun 28 21:28:35 volumio volumio[968]: info: Jun 28 21:28:35 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:35 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand stop took 40 milliseconds Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:28:35 volumio volumio[968]: info: Jun 28 21:28:35 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:35 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand status took 3 milliseconds Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand clear took 2 milliseconds Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "https://rf.proxycast.org/d43f561f-0890-4a24-af9c-f7f8788da2c8/16278-17.08.2024-ITEMA_23828949-2024C6278E0027-21.mp3" Jun 28 21:28:35 volumio volumio[968]: error: updateQueue error: null Jun 28 21:28:35 volumio volumio[968]: info: Jun 28 21:28:35 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:35 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:35 volumio volumio[968]: info: ------------------------------ 5ms Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand add "https://rf.proxycast.org/d43f561f-0890-4a24-af9c-f7f8788da2c8/16278-17.08.2024-ITEMA_23828949-2024C6278E0027-21.mp3" took 3 milliseconds Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:28:35 volumio volumio[968]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 28 21:28:35 volumio volumio[968]: info: ------------------------------ 12ms Jun 28 21:28:35 volumio volumio[968]: info: ------------------------------ 5ms Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand play took 3 milliseconds Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:35 volumio volumio[968]: info: sendMpdCommand status took 1 milliseconds Jun 28 21:28:35 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:35 volumio volumio[968]: 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://rf.proxycast.org/d43f561f-0890-4a24-af9c-f7f8788da2c8/16278-17.08.2024-ITEMA_23828949-2024C6278E0027-21.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:28:35 volumio volumio[968]: verbose: CURRENT POSITION 1 Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:35 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:35 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:35 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:36 volumio volumio[968]: info: Jun 28 21:28:36 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:36 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:28:36 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:28:36 volumio volumio[968]: info: sendMpdCommand status took 6 milliseconds Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:36 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:36 volumio volumio[968]: info: sendMpdCommand clearerror took 7 milliseconds Jun 28 21:28:36 volumio volumio[968]: info: sendMpdCommand clearerror took 6 milliseconds Jun 28 21:28:36 volumio volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 21:28:36 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:36 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:36 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:36 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:36 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:36 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:36 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:36 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:36 volumio volumio[968]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Jun 28 21:28:36 volumio volumio[968]: info: ------------------------------ 23ms Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:41 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::serviceStop Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrack 1 Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::serviceStop Jun 28 21:28:41 volumio volumio[968]: info: ControllerMpd::stop Jun 28 21:28:41 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/0 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/0 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/1 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/1 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/2 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/2 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/3 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/3 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/4 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/4 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/5 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/5 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/6 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/6 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/7 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/7 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/8 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/8 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/9 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/9 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/10 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/10 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/11 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/11 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/12 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/12 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: Adding Item to queue: podcast/20/13 Jun 28 21:28:41 volumio volumio[968]: info: Exploding uri podcast/20/13 in service podcast Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:28:41 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::play index 0 Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:41 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:41 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:41 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:28:41 volumio volumio[968]: info: sendMpdCommand stop took 38 milliseconds Jun 28 21:28:41 volumio volumio[968]: info: sendMpdCommand stop took 10 milliseconds Jun 28 21:28:41 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:41 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:28:41 volumio volumio[968]: info: Jun 28 21:28:41 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:41 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:41 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:28:41 volumio volumio[968]: info: sendMpdCommand clear took 5 milliseconds Jun 28 21:28:41 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:41 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:41 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "https://rf.proxycast.org/edb7f33d-80da-4be5-abf7-db7f72a77938/16278-24.08.2024-ITEMA_23837131-2024C6278E0035-21.mp3" Jun 28 21:28:41 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: 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,"uri":"https://rf.proxycast.org/edb7f33d-80da-4be5-abf7-db7f72a77938/16278-24.08.2024-ITEMA_23837131-2024C6278E0035-21.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:28:42 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:42 volumio volumio[968]: info: No code Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:42 volumio volumio[968]: error: updateQueue error: null Jun 28 21:28:42 volumio volumio[968]: info: Jun 28 21:28:42 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:28:42 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:28:42 volumio volumio[968]: info: ------------------------------ 30ms Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand add "https://rf.proxycast.org/edb7f33d-80da-4be5-abf7-db7f72a77938/16278-24.08.2024-ITEMA_23837131-2024C6278E0035-21.mp3" took 29 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:28:42 volumio volumio[968]: info: ------------------------------ 7ms Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand play took 5 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand status took 3 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: 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://rf.proxycast.org/edb7f33d-80da-4be5-abf7-db7f72a77938/16278-24.08.2024-ITEMA_23837131-2024C6278E0035-21.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:28:42 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:42 volumio volumio[968]: info: Jun 28 21:28:42 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:42 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand status took 9 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand status took 11 milliseconds Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand clearerror took 7 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand clearerror took 10 milliseconds Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand clearerror took 8 milliseconds Jun 28 21:28:42 volumio volumio[968]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 28 21:28:42 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:28:42 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:28:42 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:28:42 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:28:42 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:28:42 volumio volumio[968]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Jun 28 21:28:42 volumio volumio[968]: info: ------------------------------ 30ms Jun 28 21:28:46 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Jun 28 21:28:51 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::volumioPause Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::pause Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::servicePause Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::servicePause Jun 28 21:29:05 volumio volumio[968]: info: ControllerMpd::pause Jun 28 21:29:05 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand pause Jun 28 21:29:05 volumio volumio[968]: info: sendMpdCommand pause took 2 milliseconds Jun 28 21:29:05 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:05 volumio volumio[968]: info: sendMpdCommand status took 1 milliseconds Jun 28 21:29:05 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: 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,"uri":"https://rf.proxycast.org/edb7f33d-80da-4be5-abf7-db7f72a77938/16278-24.08.2024-ITEMA_23837131-2024C6278E0035-21.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:29:05 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus pause Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:29:05 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: ControllerMpd::resume Jun 28 21:29:05 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:29:05 volumio volumio[968]: info: sendMpdCommand play took 5 milliseconds Jun 28 21:29:05 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:05 volumio volumio[968]: info: sendMpdCommand status took 2 milliseconds Jun 28 21:29:05 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: 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://rf.proxycast.org/edb7f33d-80da-4be5-abf7-db7f72a77938/16278-24.08.2024-ITEMA_23837131-2024C6278E0035-21.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:29:05 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus pause Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:05 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:05 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:05 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:06 volumio volumio[968]: info: Jun 28 21:29:06 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:06 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand status took 14 milliseconds Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand status took 15 milliseconds Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand status took 16 milliseconds Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand clearerror took 10 milliseconds Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand clearerror took 10 milliseconds Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand clearerror took 8 milliseconds Jun 28 21:29:06 volumio volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 21:29:06 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:29:06 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:29:06 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:29:06 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:06 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:06 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:06 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:06 volumio volumio[968]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Jun 28 21:29:06 volumio volumio[968]: info: ------------------------------ 33ms Jun 28 21:29:29 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::ClearQueue Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:29:31 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::stPlaybackTimer Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::serviceStop Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::serviceStop Jun 28 21:29:31 volumio volumio[968]: info: ControllerMpd::stop Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::clearPlayQueue Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::addQueueItems Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::addQueueItems Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/0 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/0 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/1 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/1 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/2 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/2 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/3 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/3 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/4 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/4 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/5 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/5 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/6 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/6 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/7 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/7 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/8 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/8 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: Adding Item to queue: podcast/7/9 Jun 28 21:29:31 volumio volumio[968]: info: Exploding uri podcast/7/9 in service podcast Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::saveQueue Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushQueue Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::updateTrackBlock Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrackBlock Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPlay Jun 28 21:29:31 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::play index 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::stop Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:29:31 volumio volumio[968]: verbose: UNSET VOLATILE Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::play index undefined Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::startPlaybackTimer Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand stop took 37 milliseconds Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand stop took 10 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 21:29:31 volumio volumio[968]: info: Jun 28 21:29:31 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:29:31 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand clear took 5 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand add "https://rf.proxycast.org/0f0af017-83ff-4657-8bb2-7f5ca54a981e/14934-24.08.2024-ITEMA_23836882-2024F4934S0070-22.mp3" Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: 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,"uri":"https://rf.proxycast.org/0f0af017-83ff-4657-8bb2-7f5ca54a981e/14934-24.08.2024-ITEMA_23836882-2024F4934S0070-22.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:29:31 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::syncState stateService stop Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: info: No code Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: error: updateQueue error: null Jun 28 21:29:31 volumio volumio[968]: info: Jun 28 21:29:31 volumio volumio[968]: ---------------------------- MPD announces system playlist update Jun 28 21:29:31 volumio volumio[968]: info: Ignoring MPD Status Update Jun 28 21:29:31 volumio volumio[968]: info: ------------------------------ 25ms Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand add "https://rf.proxycast.org/0f0af017-83ff-4657-8bb2-7f5ca54a981e/14934-24.08.2024-ITEMA_23836882-2024F4934S0070-22.mp3" took 22 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand play Jun 28 21:29:31 volumio volumio[968]: info: ------------------------------ 7ms Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand play took 5 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand status took 3 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: 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://rf.proxycast.org/0f0af017-83ff-4657-8bb2-7f5ca54a981e/14934-24.08.2024-ITEMA_23836882-2024F4934S0070-22.mp3","trackType":"Podcast","serviceName":"podcast"} Jun 28 21:29:31 volumio volumio[968]: verbose: CURRENT POSITION 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::syncState stateService play Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: info: Jun 28 21:29:31 volumio volumio[968]: ---------------------------- MPD announces state update: player Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:31 volumio volumio[968]: info: ControllerMpd::getState Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand status took 5 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand status took 9 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand status took 11 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand status took 11 milliseconds Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand clearerror took 8 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseState Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand clearerror took 13 milliseconds Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand clearerror took 11 milliseconds Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand clearerror took 10 milliseconds Jun 28 21:29:31 volumio volumio[968]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 28 21:29:31 volumio volumio[968]: verbose: ControllerMpd::parseTrackInfo Jun 28 21:29:31 volumio volumio[968]: info: ControllerMpd::pushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::servicePushState Jun 28 21:29:31 volumio volumio[968]: info: CoreStateMachine::pushState Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Jun 28 21:29:31 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 21:29:31 volumio volumio[968]: info: CorePlayQueue::getTrack 0 Jun 28 21:29:31 volumio volumio[968]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Jun 28 21:29:31 volumio volumio[968]: info: ------------------------------ 31ms Jun 28 21:29:39 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Jun 28 21:29:39 volumio volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 21:29:39 volumio volumio[968]: TypeError: Cannot read property 'title' of undefined Jun 28 21:29:39 volumio volumio[968]: at /data/plugins/music_service/podcast/index.js:421:51 Jun 28 21:29:39 volumio volumio[968]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/podcast/node_modules/rss-parser/lib/utils.js:63:29) Jun 28 21:29:39 volumio volumio[968]: at ontimeout (timers.js:482:11) Jun 28 21:29:39 volumio volumio[968]: at tryOnTimeout (timers.js:317:5) Jun 28 21:29:39 volumio volumio[968]: at Timer.listOnTimeout (timers.js:277:5) Jun 28 21:29:39 volumio volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 21:29:40 volumio sudo[21997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-06-28 21:28 Jun 28 21:29:40 volumio sudo[21997]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"