-- 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"