-- Logs begin at Wed 2024-02-21 14:42:47 CST, end at Sat 2024-04-06 20:17:57 CDT. -- Apr 06 20:16:02 volumio dnsmasq-dhcp[678]: no address range available for DHCP request via wlan0 Apr 06 20:16:45 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 06 20:16:45 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365445 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365440 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365441 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365434 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365443 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365437 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365433 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365432 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365436 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365438 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365442 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365444 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365439 Apr 06 20:16:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365435 Apr 06 20:16:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365445 in service upnp_browser Apr 06 20:16:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365440 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365441 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365434 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365443 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365437 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365433 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365432 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365436 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365438 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365442 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365444 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365439 in service upnp_browser Apr 06 20:16:46 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@365435 in service upnp_browser Apr 06 20:16:48 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::ClearQueue Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::updateTrackBlock Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrackBlock Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::stPlaybackTimer Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::serviceStop Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::serviceStop Apr 06 20:16:48 volumio volumio[4581]: info: [1712452608283] ControllerUPNPBrowser::stop Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::clearPlayQueue Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::saveQueue Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushQueue Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::addQueueItems Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::addQueueItems Apr 06 20:16:48 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:16:48 volumio volumio[4581]: info: Adding Item to queue: upnp/folder/http://10.0.0.10:50001/ContentDirectory/control@22$34732 Apr 06 20:16:48 volumio volumio[4581]: info: Exploding uri upnp/folder/http://10.0.0.10:50001/ContentDirectory/control@22$34732 in service upnp_browser Apr 06 20:16:48 volumio volumio[4581]: info: Apr 06 20:16:48 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand stop took 26 milliseconds Apr 06 20:16:48 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand status took 1 milliseconds Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:48 volumio volumio[4581]: info: Apr 06 20:16:48 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:48 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:48 volumio volumio[4581]: info: Apr 06 20:16:48 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:48 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:48 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: 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":"Hotel California","artist":"Eagles","album":"Hotel California","uri":"http://10.0.0.10:50002/m/NDLNA/367320.flac","trackType":"flac"} Apr 06 20:16:48 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: No code Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: ------------------------------ 23ms Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:48 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: 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":"Hotel California","artist":"Eagles","album":"Hotel California","uri":"http://10.0.0.10:50002/m/NDLNA/367320.flac","trackType":"flac"} Apr 06 20:16:48 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: No code Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: 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":"Hotel California","artist":"Eagles","album":"Hotel California","uri":"http://10.0.0.10:50002/m/NDLNA/367320.flac","trackType":"flac"} Apr 06 20:16:48 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: No code Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:48 volumio volumio[4581]: info: ------------------------------ 46ms Apr 06 20:16:48 volumio volumio[4581]: info: ------------------------------ 47ms Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPushQueue Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::saveQueue Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::updateTrackBlock Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrackBlock Apr 06 20:16:48 volumio volumio[4581]: info: CoreCommandRouter::volumioPlay Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::play index 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::stop Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::play index undefined Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: CoreStateMachine::startPlaybackTimer Apr 06 20:16:48 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:48 volumio volumio[4581]: info: [1712452608398] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand stop took 11 milliseconds Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 20:16:48 volumio volumio[4581]: info: Apr 06 20:16:48 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:48 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:48 volumio volumio[4581]: info: sendMpdCommand clear took 4 milliseconds Apr 06 20:16:48 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.10:50002/m/NDLNA/365445.flac" Apr 06 20:16:48 volumio volumio[4581]: info: Apr 06 20:16:48 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:48 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:48 volumio volumio[4581]: info: Apr 06 20:16:48 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:48 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:48 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:16:48 volumio volumio[4581]: info: ------------------------------ 9ms Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/365445.flac" Apr 06 20:16:49 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:49 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 1169ms Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 1168ms Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/365445.flac" took 3 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand play Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:49 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:49 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 5ms Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand play took 4 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 3ms Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 2ms Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:49 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:49 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"365445.flac","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:49 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:49 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:49 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"365445.flac","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:49 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:49 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 20ms Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 25ms Apr 06 20:16:49 volumio volumio[4581]: info: Apr 06 20:16:49 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:49 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:49 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"365445.flac","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:49 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:49 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:49 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:49 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"365445.flac","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:49 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:49 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:49 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:49 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"365445.flac","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:49 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:49 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 78ms Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 76ms Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 76ms Apr 06 20:16:49 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 65 milliseconds Apr 06 20:16:49 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:49 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:49 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:49 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"365445.flac","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:49 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:49 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:49 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:49 volumio volumio[4581]: info: ------------------------------ 79ms Apr 06 20:16:50 volumio volumio[4581]: info: Apr 06 20:16:50 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:50 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:50 volumio volumio[4581]: info: Apr 06 20:16:50 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:50 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:50 volumio volumio[4581]: info: Apr 06 20:16:50 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:50 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:50 volumio volumio[4581]: info: Apr 06 20:16:50 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:50 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:50 volumio volumio[4581]: info: Apr 06 20:16:50 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:16:50 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:16:50 volumio volumio[4581]: info: Apr 06 20:16:50 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:16:50 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:16:50 volumio volumio[4581]: info: ------------------------------ 8ms Apr 06 20:16:50 volumio volumio[4581]: info: sendMpdCommand status took 7 milliseconds Apr 06 20:16:50 volumio volumio[4581]: info: ------------------------------ 7ms Apr 06 20:16:50 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:16:50 volumio volumio[4581]: info: ------------------------------ 5ms Apr 06 20:16:50 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:16:50 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 20:16:50 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 20:16:50 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:16:50 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:50 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"704 Kbps","isStreaming":false,"title":"Magical","artist":"Ed Sheeran","album":"Autumn Variations","uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:50 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:50 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:50 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:50 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"704 Kbps","isStreaming":false,"title":"Magical","artist":"Ed Sheeran","album":"Autumn Variations","uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:50 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:50 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:50 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:16:50 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":194,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"706 Kbps","isStreaming":false,"title":"Magical","artist":"Ed Sheeran","album":"Autumn Variations","uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:16:50 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:16:50 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:16:50 volumio volumio[4581]: info: ------------------------------ 47ms Apr 06 20:16:50 volumio volumio[4581]: info: ------------------------------ 47ms Apr 06 20:16:50 volumio volumio[4581]: info: ------------------------------ 46ms Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioGetQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioGetQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioGetQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioGetQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioGetQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreCommandRouter::volumioGetQueue Apr 06 20:16:50 volumio volumio[4581]: info: CoreStateMachine::getQueue Apr 06 20:16:50 volumio volumio[4581]: info: CorePlayQueue::getQueue Apr 06 20:17:12 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 06 20:17:12 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:16 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 06 20:17:16 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:19 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 06 20:17:19 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363413 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361254 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361249 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361244 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363412 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363409 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361250 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361245 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363414 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363408 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363410 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361243 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363411 Apr 06 20:17:19 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363415 Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363413 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361254 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361249 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361244 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363412 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363409 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361250 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361245 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363414 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363408 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363410 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361243 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363411 in service upnp_browser Apr 06 20:17:19 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363415 in service upnp_browser Apr 06 20:17:20 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::ClearQueue Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::stPlaybackTimer Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::updateTrackBlock Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrackBlock Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::serviceStop Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::serviceStop Apr 06 20:17:20 volumio volumio[4581]: info: [1712452640813] ControllerUPNPBrowser::stop Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::clearPlayQueue Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::saveQueue Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushQueue Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::addQueueItems Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::addQueueItems Apr 06 20:17:20 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:20 volumio volumio[4581]: info: Adding Item to queue: upnp/folder/http://10.0.0.10:50001/ContentDirectory/control@22$34510 Apr 06 20:17:20 volumio volumio[4581]: info: Exploding uri upnp/folder/http://10.0.0.10:50001/ContentDirectory/control@22$34510 in service upnp_browser Apr 06 20:17:20 volumio volumio[4581]: info: Apr 06 20:17:20 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand stop took 29 milliseconds Apr 06 20:17:20 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:20 volumio volumio[4581]: info: Apr 06 20:17:20 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:20 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:20 volumio volumio[4581]: info: Apr 06 20:17:20 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:20 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 20:17:20 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:20 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:20 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: 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":"Magical","artist":"Ed Sheeran","album":"Autumn Variations","uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:17:20 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: No code Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: 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":"Magical","artist":"Ed Sheeran","album":"Autumn Variations","uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:17:20 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: No code Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: 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":"Magical","artist":"Ed Sheeran","album":"Autumn Variations","uri":"http://10.0.0.10:50002/m/NDLNA/365445.flac","trackType":"flac"} Apr 06 20:17:20 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: No code Apr 06 20:17:20 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:20 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:20 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:20 volumio volumio[4581]: info: ------------------------------ 40ms Apr 06 20:17:20 volumio volumio[4581]: info: ------------------------------ 39ms Apr 06 20:17:20 volumio volumio[4581]: info: ------------------------------ 38ms Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushQueue Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::saveQueue Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::updateTrackBlock Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrackBlock Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPlay Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::play index 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::stop Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::play index undefined Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::startPlaybackTimer Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: info: [1712452641245] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand stop took 2 milliseconds Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:21 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand clear took 3 milliseconds Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.10:50002/m/NDLNA/363413.m4a" Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:21 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:21 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:21 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 5ms Apr 06 20:17:21 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:21 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/363413.m4a" Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 16ms Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 14ms Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:21 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/363413.m4a" took 1 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand play Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:21 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:21 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 9ms Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand play took 8 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 4ms Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 3ms Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand status took 7 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:21 volumio volumio[4581]: info: Apr 06 20:17:21 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand status took 15 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand status took 9 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"363413.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/363413.m4a","trackType":"m4a"} Apr 06 20:17:21 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"363413.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/363413.m4a","trackType":"m4a"} Apr 06 20:17:21 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:21 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"363413.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/363413.m4a","trackType":"m4a"} Apr 06 20:17:21 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:21 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 46ms Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 49ms Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 48ms Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 06 20:17:21 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:21 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"363413.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/363413.m4a","trackType":"m4a"} Apr 06 20:17:21 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:21 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":595,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"875 Kbps","isStreaming":false,"title":"363413.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/363413.m4a","trackType":"m4a"} Apr 06 20:17:21 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:21 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:21 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:21 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"875 Kbps","isStreaming":false,"title":"363413.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/363413.m4a","trackType":"m4a"} Apr 06 20:17:21 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:21 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:21 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 90ms Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 83ms Apr 06 20:17:21 volumio volumio[4581]: info: ------------------------------ 81ms Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioNext Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::next Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::next Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand next Apr 06 20:17:25 volumio volumio[4581]: info: Apr 06 20:17:25 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:25 volumio volumio[4581]: info: sendMpdCommand next took 24 milliseconds Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:25 volumio volumio[4581]: info: Apr 06 20:17:25 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:25 volumio volumio[4581]: info: Apr 06 20:17:25 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:25 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:25 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:25 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::play index undefined Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::startPlaybackTimer Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: [1712452645945] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:25 volumio volumio[4581]: info: ------------------------------ 17ms Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: sendMpdCommand status took 20 milliseconds Apr 06 20:17:25 volumio volumio[4581]: info: sendMpdCommand status took 19 milliseconds Apr 06 20:17:25 volumio volumio[4581]: info: sendMpdCommand stop took 8 milliseconds Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:25 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: No code Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:25 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: No code Apr 06 20:17:25 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:25 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:25 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:25 volumio volumio[4581]: info: ------------------------------ 39ms Apr 06 20:17:25 volumio volumio[4581]: info: ------------------------------ 40ms Apr 06 20:17:25 volumio volumio[4581]: info: Apr 06 20:17:25 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:25 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:25 volumio volumio[4581]: info: sendMpdCommand clear took 35 milliseconds Apr 06 20:17:25 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.10:50002/m/NDLNA/361254.m4a" Apr 06 20:17:25 volumio volumio[4581]: info: Apr 06 20:17:25 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:25 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:25 volumio volumio[4581]: info: Apr 06 20:17:25 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:25 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:25 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:25 volumio volumio[4581]: info: ------------------------------ 5ms Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361254.m4a" Apr 06 20:17:26 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 212ms Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 211ms Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361254.m4a" took 2 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand play Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:26 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:26 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:26 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand play took 5 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 4ms Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 3ms Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand status took 8 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand status took 7 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:26 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:26 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361254.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361254.m4a","trackType":"m4a"} Apr 06 20:17:26 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 18ms Apr 06 20:17:26 volumio volumio[4581]: info: Apr 06 20:17:26 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:26 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:26 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361254.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361254.m4a","trackType":"m4a"} Apr 06 20:17:26 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:26 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:26 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:26 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361254.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361254.m4a","trackType":"m4a"} Apr 06 20:17:26 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:26 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:26 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:26 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361254.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361254.m4a","trackType":"m4a"} Apr 06 20:17:26 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:26 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:26 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:26 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361254.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361254.m4a","trackType":"m4a"} Apr 06 20:17:26 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:26 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 57ms Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 56ms Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 56ms Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 54ms Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand status took 45 milliseconds Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:26 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 06 20:17:26 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:26 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:26 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:26 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361254.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361254.m4a","trackType":"m4a"} Apr 06 20:17:26 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:26 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:26 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:26 volumio volumio[4581]: info: ------------------------------ 72ms Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioNext Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::next Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::next Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand next Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand next took 21 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 1 milliseconds Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 1 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 1 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::play index undefined Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::startPlaybackTimer Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: [1712452650157] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 18ms Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand stop took 10 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: No code Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: No code Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 26ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 26ms Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:30 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand clear took 37 milliseconds Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.10:50002/m/NDLNA/361249.m4a" Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:30 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:30 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:30 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 5ms Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361249.m4a" Apr 06 20:17:30 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:30 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:30 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 236ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 234ms Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361249.m4a" took 3 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand play Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:30 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:30 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 5ms Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand play took 4 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 3ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 3ms Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: Apr 06 20:17:30 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 8 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361249.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361249.m4a","trackType":"m4a"} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361249.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361249.m4a","trackType":"m4a"} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:30 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361249.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361249.m4a","trackType":"m4a"} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:30 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 39ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 47ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 47ms Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 06 20:17:30 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:30 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361249.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361249.m4a","trackType":"m4a"} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:30 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361249.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361249.m4a","trackType":"m4a"} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:30 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:30 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:30 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361249.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361249.m4a","trackType":"m4a"} Apr 06 20:17:30 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:30 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:30 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 80ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 75ms Apr 06 20:17:30 volumio volumio[4581]: info: ------------------------------ 75ms Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioNext Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::next Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::next Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand next Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand next took 21 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 1 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 2 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 2 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::play index undefined Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::startPlaybackTimer Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: [1712452653077] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 19ms Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand stop took 11 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: No code Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: No code Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 29ms Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 29ms Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:33 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand clear took 35 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.10:50002/m/NDLNA/361244.m4a" Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:33 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:33 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:33 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 4ms Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361244.m4a" Apr 06 20:17:33 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:33 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:33 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 206ms Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 205ms Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361244.m4a" took 3 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand play Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:33 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:33 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 11ms Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand play took 10 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 9ms Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 9ms Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 1 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 2 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 11ms Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: Apr 06 20:17:33 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:33 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:33 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:33 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 60ms Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 58ms Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 58ms Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 45 milliseconds Apr 06 20:17:33 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 46 milliseconds Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:33 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:33 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:33 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:33 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:33 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:33 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:33 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 70ms Apr 06 20:17:33 volumio volumio[4581]: info: ------------------------------ 70ms Apr 06 20:17:43 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 06 20:17:43 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:45 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 06 20:17:45 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361296 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361297 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361295 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363435 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361300 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363434 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363433 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361292 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361293 Apr 06 20:17:45 volumio volumio[4581]: info: Preloading song: upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361294 Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361296 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361297 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361295 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363435 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361300 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363434 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@363433 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361292 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361293 in service upnp_browser Apr 06 20:17:45 volumio volumio[4581]: info: Exploding uri upnp/http://10.0.0.10:50001/ContentDirectory/control@22$@361294 in service upnp_browser Apr 06 20:17:46 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::ClearQueue Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::stPlaybackTimer Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::updateTrackBlock Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrackBlock Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::serviceStop Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::serviceStop Apr 06 20:17:46 volumio volumio[4581]: info: [1712452666723] ControllerUPNPBrowser::stop Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::clearPlayQueue Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::saveQueue Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushQueue Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::addQueueItems Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::addQueueItems Apr 06 20:17:46 volumio volumio[4581]: info: Preload queue cleared Apr 06 20:17:46 volumio volumio[4581]: info: Adding Item to queue: upnp/folder/http://10.0.0.10:50001/ContentDirectory/control@22$34518 Apr 06 20:17:46 volumio volumio[4581]: info: Exploding uri upnp/folder/http://10.0.0.10:50001/ContentDirectory/control@22$34518 in service upnp_browser Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand stop took 30 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 4 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: 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":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: No code Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: 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":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: No code Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 25ms Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 25ms Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: 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":"361244.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361244.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: No code Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 3 Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 34ms Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushQueue Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::saveQueue Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::updateTrackBlock Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrackBlock Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPlay Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::play index 0 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::stop Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::play index undefined Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::startPlaybackTimer Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:46 volumio volumio[4581]: info: [1712452666836] ControllerUPNPBrowser::clearAddPlayTrack Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand stop took 3 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:46 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand clear took 3 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.10:50002/m/NDLNA/361296.m4a" Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:46 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:46 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:46 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 6ms Apr 06 20:17:46 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:46 volumio volumio[4581]: error: updateQueue error: null Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361296.m4a" Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 45ms Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 44ms Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:46 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand add "http://10.0.0.10:50002/m/NDLNA/361296.m4a" took 2 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand play Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:46 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces system playlist update Apr 06 20:17:46 volumio volumio[4581]: info: Ignoring MPD Status Update Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 6ms Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand play took 5 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 3ms Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 2ms Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 7 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 8 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 6 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 5 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: info: Apr 06 20:17:46 volumio volumio[4581]: ---------------------------- MPD announces state update: player Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::getState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand status Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 10 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 06 20:17:46 volumio volumio[4581]: info: sendMpdCommand status took 3 milliseconds Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::parseState Apr 06 20:17:46 volumio volumio[4581]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:46 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":111,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361296.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361296.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus stop Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:46 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":111,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361296.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361296.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:46 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:46 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":111,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361296.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361296.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:46 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:46 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:46 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":111,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361296.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361296.m4a","trackType":"m4a"} Apr 06 20:17:46 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:46 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:46 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:46 volumio volumio[4581]: info: ------------------------------ 41ms Apr 06 20:17:47 volumio volumio[4581]: info: ------------------------------ 50ms Apr 06 20:17:47 volumio volumio[4581]: info: ------------------------------ 49ms Apr 06 20:17:47 volumio volumio[4581]: info: ------------------------------ 48ms Apr 06 20:17:47 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 06 20:17:47 volumio volumio[4581]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 06 20:17:47 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:47 volumio volumio[4581]: verbose: ControllerMpd::parseTrackInfo Apr 06 20:17:47 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:47 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:47 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":595,"duration":111,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"606 Kbps","isStreaming":false,"title":"361296.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361296.m4a","trackType":"m4a"} Apr 06 20:17:47 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:47 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:47 volumio volumio[4581]: info: ControllerMpd::pushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::servicePushState Apr 06 20:17:47 volumio volumio[4581]: info: CorePlayQueue::getTrack 0 Apr 06 20:17:47 volumio volumio[4581]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":111,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"606 Kbps","isStreaming":false,"title":"361296.m4a","artist":null,"album":null,"uri":"http://10.0.0.10:50002/m/NDLNA/361296.m4a","trackType":"m4a"} Apr 06 20:17:47 volumio volumio[4581]: verbose: CURRENT POSITION 0 Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::syncState stateService play Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::syncState currentStatus play Apr 06 20:17:47 volumio volumio[4581]: info: Received an update from plugin. extracting info from payload Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreStateMachine::pushState Apr 06 20:17:47 volumio volumio[4581]: info: CoreCommandRouter::volumioPushState Apr 06 20:17:47 volumio volumio[4581]: info: ------------------------------ 70ms Apr 06 20:17:47 volumio volumio[4581]: info: ------------------------------ 61ms Apr 06 20:17:57 volumio volumio[4581]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 20:17:57 volumio volumio[4581]: Error: read ETIMEDOUT Apr 06 20:17:57 volumio volumio[4581]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Apr 06 20:17:57 volumio volumio[4581]: errno: -110, Apr 06 20:17:57 volumio volumio[4581]: code: 'ETIMEDOUT', Apr 06 20:17:57 volumio volumio[4581]: syscall: 'read' Apr 06 20:17:57 volumio volumio[4581]: } Apr 06 20:17:57 volumio volumio[4581]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 20:17:57 volumio sudo[5740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 20:16 Apr 06 20:17:57 volumio sudo[5740]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"