-- Logs begin at Fri 2024-04-05 10:58:47 UTC, end at Fri 2024-04-05 14:31:38 UTC. -- Apr 05 14:30:05 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:30:05 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:05 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:05 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:05 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:05 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:05 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:05 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:05 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:05 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:05 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:05 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:05 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:05 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$39 Apr 05 14:30:05 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$39 in service upnp_browser Apr 05 14:30:06 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:06 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:06 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:06 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:06 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:06 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:06 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:06 opera volumio[12437]: info: [1712327406026] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand stop took 2 milliseconds Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:06 opera volumio[12437]: info: Apr 05 14:30:06 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:06 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand clear took 3 milliseconds Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1688.dsd" Apr 05 14:30:06 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:06 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1688.dsd" Apr 05 14:30:06 opera volumio[12437]: info: Apr 05 14:30:06 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:06 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1688.dsd" took 4 milliseconds Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:06 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:06 opera volumio[12437]: info: Apr 05 14:30:06 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:06 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand status took 3 milliseconds Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand clearerror took 2 milliseconds Apr 05 14:30:06 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:30:06 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:06 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:06 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:06 opera volumio[12437]: 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":"1688.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1688.dsd","trackType":"dsd"} Apr 05 14:30:06 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:06 opera volumio[12437]: info: No code Apr 05 14:30:06 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:06 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:06 opera volumio[12437]: info: ------------------------------ 41ms Apr 05 14:30:06 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:06 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:06 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:08 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$0 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$0 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$1 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$1 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$2 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$2 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$3 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$3 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$4 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$4 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$5 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$5 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$6 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$6 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$7 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$7 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$8 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$8 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$9 Apr 05 14:30:08 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$9 in service upnp_browser Apr 05 14:30:08 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:08 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:08 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:08 opera volumio[12437]: info: [1712327408295] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand stop took 15 milliseconds Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:08 opera volumio[12437]: info: Apr 05 14:30:08 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:08 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand clear took 5 milliseconds Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1688.dsd" Apr 05 14:30:08 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:08 opera volumio[12437]: info: ------------------------------ 5ms Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1688.dsd" Apr 05 14:30:08 opera volumio[12437]: info: Apr 05 14:30:08 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:08 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1688.dsd" took 1 milliseconds Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:08 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:08 opera volumio[12437]: info: Apr 05 14:30:08 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:08 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand status took 3 milliseconds Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand clearerror took 4 milliseconds Apr 05 14:30:08 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:30:08 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:08 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:08 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:08 opera volumio[12437]: 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":"1688.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1688.dsd","trackType":"dsd"} Apr 05 14:30:08 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:08 opera volumio[12437]: info: No code Apr 05 14:30:08 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:08 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:08 opera volumio[12437]: info: ------------------------------ 25ms Apr 05 14:30:08 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:08 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:08 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$0 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$0 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$1 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$1 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$2 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$2 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$3 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$3 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$4 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$4 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$5 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$5 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$6 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$6 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$7 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$7 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$8 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$8 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$9 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$9 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:09 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:09 opera volumio[12437]: info: [1712327409182] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand stop took 6 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:09 opera volumio[12437]: info: Apr 05 14:30:09 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:09 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand clear took 13 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1688.dsd" Apr 05 14:30:09 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:09 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1688.dsd" Apr 05 14:30:09 opera volumio[12437]: info: Apr 05 14:30:09 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:09 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1688.dsd" took 1 milliseconds Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:09 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:09 opera volumio[12437]: info: Apr 05 14:30:09 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:09 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand status took 2 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand clearerror took 2 milliseconds Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:09 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:09 opera volumio[12437]: 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":"1688.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1688.dsd","trackType":"dsd"} Apr 05 14:30:09 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: No code Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: ------------------------------ 21ms Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$0 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$0 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$1 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$1 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$2 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$2 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$3 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$3 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$4 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$4 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$5 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$5 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$6 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$6 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$7 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$7 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$8 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$8 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$9 Apr 05 14:30:09 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$39$9 in service upnp_browser Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:09 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::play index 1 Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:30:09 opera volumio[12437]: info: [1712327409810] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand stop took 7 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:09 opera volumio[12437]: info: Apr 05 14:30:09 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:09 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand clear took 4 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1689.dsd" Apr 05 14:30:09 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:09 opera volumio[12437]: info: ------------------------------ 3ms Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1689.dsd" Apr 05 14:30:09 opera volumio[12437]: info: Apr 05 14:30:09 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:09 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1689.dsd" took 1 milliseconds Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:09 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:09 opera volumio[12437]: info: Apr 05 14:30:09 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:09 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand status took 4 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand clearerror took 1 milliseconds Apr 05 14:30:09 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:30:09 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:09 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:30:09 opera volumio[12437]: 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":"1689.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1689.dsd","trackType":"dsd"} Apr 05 14:30:09 opera volumio[12437]: verbose: CURRENT POSITION 1 Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: No code Apr 05 14:30:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:09 opera volumio[12437]: info: ------------------------------ 25ms Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:19 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:20 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:20 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$37 Apr 05 14:30:20 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$37 in service upnp_browser Apr 05 14:30:20 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:20 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:20 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:20 opera volumio[12437]: info: [1712327420199] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand stop took 5 milliseconds Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:20 opera volumio[12437]: info: Apr 05 14:30:20 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:20 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand clear took 9 milliseconds Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1660.dsd" Apr 05 14:30:20 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:20 opera volumio[12437]: info: ------------------------------ 4ms Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1660.dsd" Apr 05 14:30:20 opera volumio[12437]: info: Apr 05 14:30:20 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:20 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1660.dsd" took 1 milliseconds Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:20 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:20 opera volumio[12437]: info: Apr 05 14:30:20 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:20 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand status took 3 milliseconds Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand clearerror took 2 milliseconds Apr 05 14:30:20 opera volumio[12437]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 05 14:30:20 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:20 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:20 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:20 opera volumio[12437]: 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":"1660.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1660.dsd","trackType":"dsd"} Apr 05 14:30:20 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:20 opera volumio[12437]: info: No code Apr 05 14:30:20 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:20 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:20 opera volumio[12437]: info: ------------------------------ 19ms Apr 05 14:30:20 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:20 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:20 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:23 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:23 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:23 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:23 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:23 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:23 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:23 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:23 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:23 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:23 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:23 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:23 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$0 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$0 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$1 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$1 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$2 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$2 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$3 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$3 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$4 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$4 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$5 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$5 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$6 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$6 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$7 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$7 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$8 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$8 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$9 Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$9 in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$A Apr 05 14:30:23 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$A in service upnp_browser Apr 05 14:30:23 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:24 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::play index 5 Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrack 5 Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrack 5 Apr 05 14:30:24 opera volumio[12437]: info: [1712327424050] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand stop took 13 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:24 opera volumio[12437]: info: Apr 05 14:30:24 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:24 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand clear took 2 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1668.dsd" Apr 05 14:30:24 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:24 opera volumio[12437]: info: ------------------------------ 4ms Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1668.dsd" Apr 05 14:30:24 opera volumio[12437]: info: Apr 05 14:30:24 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:24 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1668.dsd" took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:24 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: info: Apr 05 14:30:24 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:24 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand status took 4 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand clearerror took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:24 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrack 5 Apr 05 14:30:24 opera volumio[12437]: 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":"1668.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1668.dsd","trackType":"dsd"} Apr 05 14:30:24 opera volumio[12437]: verbose: CURRENT POSITION 5 Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: No code Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: ------------------------------ 29ms Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$0 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$0 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$1 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$1 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$2 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$2 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$3 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$3 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$4 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$4 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$5 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$5 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$6 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$6 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$7 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$7 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$8 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$8 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$9 Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$9 in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$A Apr 05 14:30:24 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$37$A in service upnp_browser Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:24 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::play index 5 Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrack 5 Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrack 5 Apr 05 14:30:24 opera volumio[12437]: info: [1712327424248] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand stop took 6 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:30:24 opera volumio[12437]: info: Apr 05 14:30:24 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:24 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand clear took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/1668.dsd" Apr 05 14:30:24 opera volumio[12437]: error: updateQueue error: null Apr 05 14:30:24 opera volumio[12437]: info: ------------------------------ 9ms Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1668.dsd" Apr 05 14:30:24 opera volumio[12437]: info: Apr 05 14:30:24 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:30:24 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/1668.dsd" took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:30:24 opera volumio[12437]: info: ------------------------------ 3ms Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:30:24 opera volumio[12437]: info: Apr 05 14:30:24 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:30:24 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand status took 5 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand clearerror took 2 milliseconds Apr 05 14:30:24 opera volumio[12437]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 14:30:24 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:30:24 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: CorePlayQueue::getTrack 5 Apr 05 14:30:24 opera volumio[12437]: 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":"1668.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/1668.dsd","trackType":"dsd"} Apr 05 14:30:24 opera volumio[12437]: verbose: CURRENT POSITION 5 Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: No code Apr 05 14:30:24 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:24 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:24 opera volumio[12437]: info: ------------------------------ 30ms Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:24 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:38 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:30:39 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:39 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:39 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:39 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:39 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:39 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:39 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:39 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:39 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$2E Apr 05 14:30:39 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$2E in service upnp_browser Apr 05 14:30:39 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:39 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:39 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:39 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:39 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:39 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:39 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:39 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:39 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:41 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:41 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:41 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:41 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:41 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:41 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:41 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:41 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:41 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$2E Apr 05 14:30:41 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$2E in service upnp_browser Apr 05 14:30:41 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:41 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:41 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:41 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:41 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:41 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:41 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:41 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:41 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:30:51 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:52 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:52 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:30:52 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:30:52 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:30:52 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$22$0 Apr 05 14:30:52 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$22$0 in service upnp_browser Apr 05 14:30:52 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:30:52 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:30:52 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:30:52 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:30:52 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:52 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:30:52 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:30:52 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:06 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:31:07 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:31:08 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:08 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:08 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:31:08 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$13$0 Apr 05 14:31:08 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$13$0 in service upnp_browser Apr 05 14:31:08 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:08 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:31:08 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:31:08 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:08 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:31:08 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:08 opera volumio[12437]: info: [1712327468329] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:31:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:31:08 opera volumio[12437]: info: sendMpdCommand stop took 9 milliseconds Apr 05 14:31:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:31:08 opera volumio[12437]: info: Apr 05 14:31:08 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:08 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:08 opera volumio[12437]: info: sendMpdCommand clear took 10 milliseconds Apr 05 14:31:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/437.wav" Apr 05 14:31:08 opera volumio[12437]: error: updateQueue error: null Apr 05 14:31:08 opera volumio[12437]: info: ------------------------------ 3ms Apr 05 14:31:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/437.wav" Apr 05 14:31:08 opera volumio[12437]: info: Apr 05 14:31:08 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:08 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:08 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/437.wav" took 1 milliseconds Apr 05 14:31:08 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:31:08 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:31:08 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:31:08 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:31:09 opera volumio[12437]: info: Apr 05 14:31:09 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:09 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:09 opera volumio[12437]: info: Apr 05 14:31:09 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:09 opera volumio[12437]: info: sendMpdCommand status took 2 milliseconds Apr 05 14:31:09 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:09 opera volumio[12437]: info: sendMpdCommand status took 1 milliseconds Apr 05 14:31:09 opera volumio[12437]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:09 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:09 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:09 opera volumio[12437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"437.wav","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/437.wav","trackType":"wav"} Apr 05 14:31:09 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::syncState stateService play Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:09 opera volumio[12437]: info: ------------------------------ 15ms Apr 05 14:31:09 opera volumio[12437]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 05 14:31:09 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:09 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:09 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:09 opera volumio[12437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"437.wav","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/437.wav","trackType":"wav"} Apr 05 14:31:09 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::syncState stateService play Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus play Apr 05 14:31:09 opera volumio[12437]: info: Received an update from plugin. extracting info from payload Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:09 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:09 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:09 opera volumio[12437]: info: ------------------------------ 35ms Apr 05 14:31:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:09 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::volumioNext Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::next Apr 05 14:31:11 opera volumio[12437]: info: ControllerMpd::next Apr 05 14:31:11 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand next Apr 05 14:31:11 opera volumio[12437]: info: sendMpdCommand next took 118 milliseconds Apr 05 14:31:11 opera volumio[12437]: info: Apr 05 14:31:11 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:11 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:11 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:11 opera volumio[12437]: info: sendMpdCommand status took 1 milliseconds Apr 05 14:31:11 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:11 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:11 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:11 opera volumio[12437]: 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 05 14:31:11 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus play Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:11 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:11 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:31:11 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:11 opera volumio[12437]: info: [1712327471962] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:31:11 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:31:11 opera volumio[12437]: info: ------------------------------ 17ms Apr 05 14:31:11 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:11 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:11 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:11 opera volumio[12437]: info: sendMpdCommand stop took 10 milliseconds Apr 05 14:31:11 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:31:11 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:11 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:11 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:11 opera volumio[12437]: info: Apr 05 14:31:11 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:11 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:11 opera volumio[12437]: info: sendMpdCommand clear took 12 milliseconds Apr 05 14:31:11 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/441.wav" Apr 05 14:31:11 opera volumio[12437]: error: updateQueue error: null Apr 05 14:31:11 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/441.wav" Apr 05 14:31:12 opera volumio[12437]: info: Apr 05 14:31:12 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:12 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:12 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/441.wav" took 1 milliseconds Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:31:12 opera volumio[12437]: info: ------------------------------ 1ms Apr 05 14:31:12 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:31:12 opera volumio[12437]: info: Apr 05 14:31:12 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:12 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:12 opera volumio[12437]: info: sendMpdCommand status took 2 milliseconds Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:12 opera volumio[12437]: info: Apr 05 14:31:12 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:12 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:12 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:12 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:12 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:12 opera volumio[12437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"441.wav","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/441.wav","trackType":"wav"} Apr 05 14:31:12 opera volumio[12437]: verbose: CURRENT POSITION 1 Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::syncState stateService play Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:12 opera volumio[12437]: info: ------------------------------ 12ms Apr 05 14:31:12 opera volumio[12437]: info: sendMpdCommand status took 9 milliseconds Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:12 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:12 opera volumio[12437]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 05 14:31:12 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:12 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:12 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:12 opera volumio[12437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"441.wav","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/441.wav","trackType":"wav"} Apr 05 14:31:12 opera volumio[12437]: verbose: CURRENT POSITION 1 Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::syncState stateService play Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus play Apr 05 14:31:12 opera volumio[12437]: info: Received an update from plugin. extracting info from payload Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:12 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:12 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:12 opera volumio[12437]: info: ------------------------------ 29ms Apr 05 14:31:12 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:12 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:20 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:21 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::stPlaybackTimer Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::serviceStop Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::serviceStop Apr 05 14:31:21 opera volumio[12437]: info: [1712327481269] ControllerUPNPBrowser::stop Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$27 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$27 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$0 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$0 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$2 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$2 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$3 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$3 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$4 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$4 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$5 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$5 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$6 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$6 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$7 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$7 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$8 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$8 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$9 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$9 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$A Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$A in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$B Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$B in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$C Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$C in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$D Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$D in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$E Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$E in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$F Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$F in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$10 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$10 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$11 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$11 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$12 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$12 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$13 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$13 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$14 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$14 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$15 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$15 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$16 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$16 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$17 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$17 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$18 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$18 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$19 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$19 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1A Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1A in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1B Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1B in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1C Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1C in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1D Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1D in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1E Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1E in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1F Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$1F in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$20 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$20 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$21 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$21 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$22 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$22 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$23 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$23 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$24 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$24 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Adding Item to queue: upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$25 Apr 05 14:31:21 opera volumio[12437]: info: Exploding uri upnp/http://192.168.100.147:8200/ctl/ContentDir@64$1$E$25 in service upnp_browser Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: Apr 05 14:31:21 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand stop took 117 milliseconds Apr 05 14:31:21 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand status took 1 milliseconds Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:21 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: 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":"441.wav","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/441.wav","trackType":"wav"} Apr 05 14:31:21 opera volumio[12437]: verbose: CURRENT POSITION 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: No code Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: ------------------------------ 16ms Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:31:21 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::play index 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:21 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: info: [1712327481601] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand stop took 2 milliseconds Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:31:21 opera volumio[12437]: info: Apr 05 14:31:21 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:21 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand clear took 3 milliseconds Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/273.dsd" Apr 05 14:31:21 opera volumio[12437]: error: updateQueue error: null Apr 05 14:31:21 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/273.dsd" Apr 05 14:31:21 opera volumio[12437]: info: Apr 05 14:31:21 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:21 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/273.dsd" took 1 milliseconds Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:31:21 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:31:21 opera volumio[12437]: info: Apr 05 14:31:21 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:21 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand status took 3 milliseconds Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand clearerror took 2 milliseconds Apr 05 14:31:21 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:31:21 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:21 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: CorePlayQueue::getTrack 1 Apr 05 14:31:21 opera volumio[12437]: 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":"273.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/273.dsd","trackType":"dsd"} Apr 05 14:31:21 opera volumio[12437]: verbose: CURRENT POSITION 1 Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: No code Apr 05 14:31:21 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:21 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:21 opera volumio[12437]: info: ------------------------------ 21ms Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:21 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:23 opera nmbd[1009]: [2024/04/05 14:31:23.422834, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 05 14:31:23 opera nmbd[1009]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.37 for name WORKGROUP<1d>. Apr 05 14:31:23 opera nmbd[1009]: This response was from IP 192.168.100.147, reporting an IP address of 192.168.100.147. Apr 05 14:31:25 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:31:26 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:26 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:26 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:31:26 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:26 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:26 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:26 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:31:26 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:31:26 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$0 Apr 05 14:31:26 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$0 in service upnp_browser Apr 05 14:31:26 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:26 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:26 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:31:26 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:31:26 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:31:26 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:26 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:31:26 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:26 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:28 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:28 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:28 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:31:28 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:28 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:28 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:28 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:31:28 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:31:28 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$0 Apr 05 14:31:28 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$1$0 in service upnp_browser Apr 05 14:31:28 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:28 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:28 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:31:28 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:31:28 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:31:28 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:28 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:31:28 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:28 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:31 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:31:34 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::ClearQueue Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:35 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::clearPlayQueue Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::addQueueItems Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::addQueueItems Apr 05 14:31:35 opera volumio[12437]: info: Adding Item to queue: upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$2$1 Apr 05 14:31:35 opera volumio[12437]: info: Exploding uri upnp/folder/http://192.168.100.147:8200/ctl/ContentDir@64$2$1 in service upnp_browser Apr 05 14:31:35 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::saveQueue Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPushQueue Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::updateTrackBlock Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::getTrackBlock Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPlay Apr 05 14:31:35 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::play index 0 Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::stop Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:35 opera volumio[12437]: verbose: UNSET VOLATILE Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::play index undefined Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::startPlaybackTimer Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:35 opera volumio[12437]: info: [1712327495609] ControllerUPNPBrowser::clearAddPlayTrack Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand stop took 2 milliseconds Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 14:31:35 opera volumio[12437]: info: Apr 05 14:31:35 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:35 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand clear took 3 milliseconds Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.147:8200/MediaItems/2619.dsd" Apr 05 14:31:35 opera volumio[12437]: error: updateQueue error: null Apr 05 14:31:35 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.147:8200/MediaItems/2619.dsd" Apr 05 14:31:35 opera volumio[12437]: info: Apr 05 14:31:35 opera volumio[12437]: ---------------------------- MPD announces system playlist update Apr 05 14:31:35 opera volumio[12437]: info: Ignoring MPD Status Update Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand add "http://192.168.100.147:8200/MediaItems/2619.dsd" took 1 milliseconds Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand play Apr 05 14:31:35 opera volumio[12437]: info: ------------------------------ 2ms Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand play took 1 milliseconds Apr 05 14:31:35 opera volumio[12437]: info: Apr 05 14:31:35 opera volumio[12437]: ---------------------------- MPD announces state update: player Apr 05 14:31:35 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:35 opera volumio[12437]: info: ControllerMpd::getState Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand status Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand status took 3 milliseconds Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::parseState Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand clearerror took 2 milliseconds Apr 05 14:31:35 opera volumio[12437]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 14:31:35 opera volumio[12437]: verbose: ControllerMpd::parseTrackInfo Apr 05 14:31:35 opera volumio[12437]: info: ControllerMpd::pushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::servicePushState Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:35 opera volumio[12437]: info: CorePlayQueue::getTrack 0 Apr 05 14:31:35 opera volumio[12437]: 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":"2619.dsd","artist":null,"album":null,"uri":"http://192.168.100.147:8200/MediaItems/2619.dsd","trackType":"dsd"} Apr 05 14:31:35 opera volumio[12437]: verbose: CURRENT POSITION 0 Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::syncState stateService stop Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::syncState currentStatus stop Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:35 opera volumio[12437]: info: No code Apr 05 14:31:35 opera volumio[12437]: info: CoreStateMachine::pushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::volumioPushState Apr 05 14:31:35 opera volumio[12437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 14:31:35 opera volumio[12437]: info: ------------------------------ 19ms Apr 05 14:31:35 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:35 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:35 opera volumio[12437]: info: Updating RAAT Signal Path Apr 05 14:31:38 opera volumio[12437]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 14:31:38 opera volumio[12437]: { Error: connect ECONNREFUSED 162.125.32.13:443 Apr 05 14:31:38 opera volumio[12437]: at Object._errnoException (util.js:1022:11) Apr 05 14:31:38 opera volumio[12437]: at _exceptionWithHostPort (util.js:1044:20) Apr 05 14:31:38 opera volumio[12437]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 05 14:31:38 opera volumio[12437]: code: 'ECONNREFUSED', Apr 05 14:31:38 opera volumio[12437]: errno: 'ECONNREFUSED', Apr 05 14:31:38 opera volumio[12437]: syscall: 'connect', Apr 05 14:31:38 opera volumio[12437]: address: '162.125.32.13', Apr 05 14:31:38 opera volumio[12437]: port: 443 } Apr 05 14:31:38 opera volumio[12437]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 14:31:38 opera sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-05 14:30 Apr 05 14:31:38 opera sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"