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