Mar 24 09:58:03 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:03 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:07 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:07 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:14 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:14 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:17 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:17 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:19 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:19 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296839 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296837 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296833 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296835 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296838 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296832 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296836 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296831 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296834 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296841 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296830 Mar 24 09:58:19 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296840 Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296839 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296837 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296833 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296835 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296838 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296832 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296836 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296831 in service upnp_browser Mar 24 09:58:19 minivolumio nmbd[1050]: [2026/03/24 09:58:19.634795, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 24 09:58:19 minivolumio nmbd[1050]: query_name_response: Multiple (2) responses received for a query on subnet 10.75.0.200 for name WORKGROUP<1d>. Mar 24 09:58:19 minivolumio nmbd[1050]: This response was from IP 10.75.0.190, reporting an IP address of 10.75.0.190. Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296834 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296841 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296830 in service upnp_browser Mar 24 09:58:19 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@296840 in service upnp_browser Mar 24 09:58:20 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::ClearQueue Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::stop Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::clearPlayQueue Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::addQueueItems Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::addQueueItems Mar 24 09:58:20 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:20 minivolumio volumio[532332]: info: Adding Item to queue: upnp/folder/http://10.75.0.100:50001/ContentDirectory/control@22$37919 Mar 24 09:58:20 minivolumio volumio[532332]: info: Exploding uri upnp/folder/http://10.75.0.100:50001/ContentDirectory/control@22$37919 in service upnp_browser Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::updateTrackBlock Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::getTrackBlock Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPlay Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::play index 0 Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::stop Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::play index undefined Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:20 minivolumio volumio[532332]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:58:20 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:20 minivolumio volumio[532332]: info: [1774317500965] ControllerUPNPBrowser::clearAddPlayTrack Mar 24 09:58:20 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:58:20 minivolumio volumio[532332]: info: sendMpdCommand stop took 4 milliseconds Mar 24 09:58:20 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:58:20 minivolumio volumio[532332]: info: Mar 24 09:58:20 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:20 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:20 minivolumio volumio[532332]: info: sendMpdCommand clear took 3 milliseconds Mar 24 09:58:20 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand load "http://10.75.0.100:50002/m/NDLNA/296839.dsf" Mar 24 09:58:20 minivolumio volumio[532332]: info: Mar 24 09:58:20 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:20 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:20 minivolumio volumio[532332]: info: Mar 24 09:58:20 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:20 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:20 minivolumio volumio[532332]: error: updateQueue error: null Mar 24 09:58:20 minivolumio volumio[532332]: info: ------------------------------ 8ms Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand add "http://10.75.0.100:50002/m/NDLNA/296839.dsf" Mar 24 09:58:21 minivolumio volumio[532332]: error: updateQueue error: null Mar 24 09:58:21 minivolumio volumio[532332]: error: updateQueue error: null Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:21 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 41ms Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 41ms Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand add "http://10.75.0.100:50002/m/NDLNA/296839.dsf" took 5 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand play Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:21 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:21 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 9ms Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand play took 6 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 6ms Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 4ms Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand status took 43 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand status took 43 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand status took 42 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand status took 2 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:21 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:21 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus stop Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 52ms Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:21 minivolumio volumio[532332]: info: Mar 24 09:58:21 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand status took 4 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand status took 2 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:21 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:21 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:21 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:21 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:21 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:21 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:21 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:21 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:21 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 79ms Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 78ms Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 40ms Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:21 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:21 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:21 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:21 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:21 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:21 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:21 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:21 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 54ms Mar 24 09:58:21 minivolumio volumio[532332]: info: ------------------------------ 53ms Mar 24 09:58:35 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:35 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:40 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:40 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:40 minivolumio volumio[532332]: info: Preloading song: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@297005 Mar 24 09:58:40 minivolumio volumio[532332]: info: Exploding uri upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@297005 in service upnp_browser Mar 24 09:58:43 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::ClearQueue Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::stPlaybackTimer Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::updateTrackBlock Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrackBlock Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::serviceStop Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::serviceStop Mar 24 09:58:43 minivolumio volumio[532332]: info: [1774317523343] ControllerUPNPBrowser::stop Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::clearPlayQueue Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::addQueueItems Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::addQueueItems Mar 24 09:58:43 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:43 minivolumio volumio[532332]: info: Adding Item to queue: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@297005 Mar 24 09:58:43 minivolumio volumio[532332]: info: Using cached record of: upnp/http://10.75.0.100:50001/ContentDirectory/control@22$@297005 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::updateTrackBlock Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrackBlock Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPlay Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::play index 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::play index undefined Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: Mar 24 09:58:43 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand stop took 20 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:43 minivolumio volumio[532332]: info: Mar 24 09:58:43 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:43 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:43 minivolumio volumio[532332]: info: Mar 24 09:58:43 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:43 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand status took 8 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand status took 7 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand status took 2 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:43 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:43 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: 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":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:43 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: No code Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: 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":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:43 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: No code Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: 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":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:43 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus stop Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: No code Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:43 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:43 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:43 minivolumio volumio[532332]: info: ------------------------------ 47ms Mar 24 09:58:43 minivolumio volumio[532332]: info: ------------------------------ 46ms Mar 24 09:58:43 minivolumio volumio[532332]: info: ------------------------------ 45ms Mar 24 09:58:54 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:54 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:55 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:55 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:56 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:58:56 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:57 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::ClearQueue Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::stop Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::clearPlayQueue Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::addQueueItems Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::addQueueItems Mar 24 09:58:57 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:58:57 minivolumio volumio[532332]: info: Adding Item to queue: upnp/folder/http://10.75.0.100:50001/ContentDirectory/control@22$37919 Mar 24 09:58:57 minivolumio volumio[532332]: info: Using cached record of: upnp/folder/http://10.75.0.100:50001/ContentDirectory/control@22$37919 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::updateTrackBlock Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrackBlock Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPlay Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::play index 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::stop Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::play index undefined Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: [1774317537390] ControllerUPNPBrowser::clearAddPlayTrack Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand stop took 2 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:57 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand clear took 1 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand load "http://10.75.0.100:50002/m/NDLNA/296839.dsf" Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:57 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:57 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:57 minivolumio volumio[532332]: error: updateQueue error: null Mar 24 09:58:57 minivolumio volumio[532332]: error: updateQueue error: null Mar 24 09:58:57 minivolumio volumio[532332]: error: updateQueue error: null Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 7ms Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand add "http://10.75.0.100:50002/m/NDLNA/296839.dsf" Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 6ms Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 5ms Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:57 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand add "http://10.75.0.100:50002/m/NDLNA/296839.dsf" took 1 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand play Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:57 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces system playlist update Mar 24 09:58:57 minivolumio volumio[532332]: info: Ignoring MPD Status Update Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 3ms Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand play took 3 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 2ms Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 2ms Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand status took 16 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand status took 20 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand status took 20 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand status took 5 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand status took 4 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:57 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus stop Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 28ms Mar 24 09:58:57 minivolumio volumio[532332]: info: Mar 24 09:58:57 minivolumio volumio[532332]: ---------------------------- MPD announces state update: player Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::getState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand status took 8 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseState Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":490,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:57 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:57 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":501,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:57 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:57 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:57 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:57 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:57 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:57 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 67ms Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 67ms Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 54ms Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 52ms Mar 24 09:58:57 minivolumio volumio[532332]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 24 09:58:57 minivolumio volumio[532332]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:58:57 minivolumio volumio[532332]: info: ControllerMpd::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::servicePushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CorePlayQueue::getTrack 0 Mar 24 09:58:57 minivolumio volumio[532332]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":270,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"296839.dsf","artist":null,"album":null,"uri":"http://10.75.0.100:50002/m/NDLNA/296839.dsf","trackType":"dsf"} Mar 24 09:58:57 minivolumio volumio[532332]: verbose: CURRENT POSITION 0 Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState stateService play Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:58:57 minivolumio volumio[532332]: info: Received an update from plugin. extracting info from payload Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreStateMachine::pushState Mar 24 09:58:57 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushState Mar 24 09:58:57 minivolumio volumio[532332]: info: ------------------------------ 50ms Mar 24 09:59:05 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:59:05 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:59:07 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:59:07 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:59:12 minivolumio volumio[532332]: info: CoreCommandRouter::volumioAddQueueItems Mar 24 09:59:12 minivolumio volumio[532332]: info: CoreStateMachine::addQueueItems Mar 24 09:59:12 minivolumio volumio[532332]: info: CorePlayQueue::addQueueItems Mar 24 09:59:12 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:59:12 minivolumio volumio[532332]: info: Adding Item to queue: upnp/folder/http://10.75.0.100:50001/ContentDirectory/control@22$37953 Mar 24 09:59:12 minivolumio volumio[532332]: info: Exploding uri upnp/folder/http://10.75.0.100:50001/ContentDirectory/control@22$37953 in service upnp_browser Mar 24 09:59:12 minivolumio volumio[532332]: info: CoreCommandRouter::volumioPushQueue Mar 24 09:59:12 minivolumio volumio[532332]: info: CorePlayQueue::saveQueue Mar 24 09:59:12 minivolumio volumio[532332]: info: CoreStateMachine::updateTrackBlock Mar 24 09:59:12 minivolumio volumio[532332]: info: CorePlayQueue::getTrackBlock Mar 24 09:59:23 minivolumio volumio[532332]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 24 09:59:23 minivolumio volumio[532332]: info: Preload queue cleared Mar 24 09:59:36 minivolumio volumio[532332]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 09:59:36 minivolumio volumio[532332]: Error: connect ETIMEDOUT 128.242.240.91:443 Mar 24 09:59:36 minivolumio volumio[532332]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 24 09:59:36 minivolumio volumio[532332]: errno: -110, Mar 24 09:59:36 minivolumio volumio[532332]: code: 'ETIMEDOUT', Mar 24 09:59:36 minivolumio volumio[532332]: syscall: 'connect', Mar 24 09:59:36 minivolumio volumio[532332]: address: '128.242.240.91', Mar 24 09:59:36 minivolumio volumio[532332]: port: 443 Mar 24 09:59:36 minivolumio volumio[532332]: } Mar 24 09:59:36 minivolumio volumio[532332]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 09:59:36 minivolumio sudo[712871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 09:58' Mar 24 09:59:36 minivolumio sudo[712871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"