-- Logs begin at Wed 2024-02-21 20:42:47 UTC, end at Mon 2024-04-22 10:15:49 UTC. --
Apr 22 10:14:01 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:01 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975 in service upnp_browser
Apr 22 10:14:01 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976 in service upnp_browser
Apr 22 10:14:02 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974 in service upnp_browser
Apr 22 10:14:03 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::ClearQueue
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::stPlaybackTimer
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::serviceStop
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::serviceStop
Apr 22 10:14:03 volumio volumio[588]: info: [1713780843356] ControllerUPNPBrowser::stop
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::clearPlayQueue
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::addQueueItems
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::addQueueItems
Apr 22 10:14:03 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d_757ed1a7
Apr 22 10:14:03 volumio volumio[588]: info: Exploding uri upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d_757ed1a7 in service upnp_browser
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:03 volumio volumio[588]: info:
Apr 22 10:14:03 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand stop took 155 milliseconds
Apr 22 10:14:03 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand status took 7 milliseconds
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:03 volumio volumio[588]: info:
Apr 22 10:14:03 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:03 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:03 volumio volumio[588]: info:
Apr 22 10:14:03 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:03 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand status took 22 milliseconds
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand status took 12 milliseconds
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:03 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: 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":"Alfonsina y el Mar","artist":"Rita Payes & Elisabeth Roma","album":"Imagina","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfMjhlNDNhMzUtNDU5MjM","trackType":""}
Apr 22 10:14:03 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: No code
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: ------------------------------ 176ms
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPlay
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::play index 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::play index undefined
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: [1713780843766] ControllerUPNPBrowser::clearAddPlayTrack
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand playlistinfo took 193 milliseconds
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand playlistinfo took 191 milliseconds
Apr 22 10:14:03 volumio volumio[588]: info: sendMpdCommand stop took 12 milliseconds
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand clear
Apr 22 10:14:03 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: 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":"Alfonsina y el Mar","artist":"Rita Payes & Elisabeth Roma","album":"Imagina","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfMjhlNDNhMzUtNDU5MjM","trackType":""}
Apr 22 10:14:03 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: No code
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: 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":"Alfonsina y el Mar","artist":"Rita Payes & Elisabeth Roma","album":"Imagina","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfMjhlNDNhMzUtNDU5MjM","trackType":""}
Apr 22 10:14:03 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: No code
Apr 22 10:14:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:03 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:03 volumio volumio[588]: info: ------------------------------ 406ms
Apr 22 10:14:03 volumio volumio[588]: info: ------------------------------ 401ms
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand clear took 246 milliseconds
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx"
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:04 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:04 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 205ms
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx"
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 32ms
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 25ms
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx" took 52 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand play
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 76ms
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand play took 65 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 237ms
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 210ms
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand status took 73 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand status took 63 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand status took 64 milliseconds
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:04 volumio volumio[588]: info:
Apr 22 10:14:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand status took 93 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: ------------------------------ 125ms
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand status took 106 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand playlistinfo took 80 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand playlistinfo took 66 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand playlistinfo took 65 milliseconds
Apr 22 10:14:04 volumio volumio[588]: info: sendMpdCommand status took 58 milliseconds
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:04 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:04 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:04 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:04 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:04 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:04 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:04 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:04 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:04 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:04 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"767 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:04 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:04 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:05 volumio volumio[588]: info: ------------------------------ 494ms
Apr 22 10:14:05 volumio volumio[588]: info: ------------------------------ 698ms
Apr 22 10:14:05 volumio volumio[588]: info: ------------------------------ 703ms
Apr 22 10:14:05 volumio volumio[588]: info:
Apr 22 10:14:05 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:05 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:05 volumio volumio[588]: info:
Apr 22 10:14:05 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:05 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:05 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:05 volumio volumio[588]: info: sendMpdCommand playlistinfo took 578 milliseconds
Apr 22 10:14:05 volumio volumio[588]: info: sendMpdCommand playlistinfo took 597 milliseconds
Apr 22 10:14:05 volumio volumio[588]: info: sendMpdCommand playlistinfo took 580 milliseconds
Apr 22 10:14:05 volumio volumio[588]: info: ------------------------------ 112ms
Apr 22 10:14:05 volumio volumio[588]: info: sendMpdCommand status took 93 milliseconds
Apr 22 10:14:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:05 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:05 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:05 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"513 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:05 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:05 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:05 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"513 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:05 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:05 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:05 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"506 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:05 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:05 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:06 volumio volumio[588]: info: ------------------------------ 1826ms
Apr 22 10:14:06 volumio volumio[588]: info: ------------------------------ 1851ms
Apr 22 10:14:06 volumio volumio[588]: info: ------------------------------ 1809ms
Apr 22 10:14:06 volumio volumio[588]: info:
Apr 22 10:14:06 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:06 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:06 volumio volumio[588]: info:
Apr 22 10:14:06 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:06 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:06 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:06 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:06 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:06 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:06 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:06 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:06 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:06 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:06 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:06 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:06 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:07 volumio volumio[588]: info: sendMpdCommand playlistinfo took 2134 milliseconds
Apr 22 10:14:07 volumio volumio[588]: info: ------------------------------ 1036ms
Apr 22 10:14:07 volumio volumio[588]: info: sendMpdCommand status took 1011 milliseconds
Apr 22 10:14:07 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:07 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:07 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:07 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:07 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:07 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"515 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:07 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:07 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:07 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:07 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:07 volumio volumio[588]: info: ------------------------------ 2643ms
Apr 22 10:14:07 volumio volumio[588]: info: sendMpdCommand playlistinfo took 353 milliseconds
Apr 22 10:14:07 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:07 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:08 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:08 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2974,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"542 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:08 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:08 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:08 volumio volumio[588]: info: ------------------------------ 1748ms
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:08 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:08 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioSeek
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::seek
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:21 volumio volumio[588]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","realUri":"upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971","type":"song","albumart":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx.jpg?albumArt=true","artist":"Electric Light Orchestra","album":"Eldorado","name":"Can't Get It Out Of My Head","title":"Can't Get It Out Of My Head","duration":261}
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:21 volumio volumio[588]: info: [1713780861116] ControllerUPNPBrowser::seek
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::seek
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: error: null
Apr 22 10:14:21 volumio volumio[588]: info:
Apr 22 10:14:21 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:21 volumio volumio[588]: info:
Apr 22 10:14:21 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:21 volumio volumio[588]: info:
Apr 22 10:14:21 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: sendMpdCommand status took 99 milliseconds
Apr 22 10:14:21 volumio volumio[588]: info: sendMpdCommand status took 85 milliseconds
Apr 22 10:14:21 volumio volumio[588]: info: sendMpdCommand status took 63 milliseconds
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:21 volumio volumio[588]: info: sendMpdCommand playlistinfo took 28 milliseconds
Apr 22 10:14:21 volumio volumio[588]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 22 10:14:21 volumio volumio[588]: info: sendMpdCommand playlistinfo took 22 milliseconds
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:21 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:21 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:21 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:21 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:21 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:21 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:21 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:21 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21617,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"413 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:21 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:21 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:21 volumio volumio[588]: info: ------------------------------ 538ms
Apr 22 10:14:21 volumio volumio[588]: info: ------------------------------ 530ms
Apr 22 10:14:21 volumio volumio[588]: info: ------------------------------ 522ms
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:21 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:21 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioSeek
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::seek
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:23 volumio volumio[588]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","realUri":"upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971","type":"song","albumart":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx.jpg?albumArt=true","artist":"Electric Light Orchestra","album":"Eldorado","name":"Can't Get It Out Of My Head","title":"Can't Get It Out Of My Head","duration":261}
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:23 volumio volumio[588]: info: [1713780863110] ControllerUPNPBrowser::seek
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::seek
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: error: null
Apr 22 10:14:23 volumio volumio[588]: info:
Apr 22 10:14:23 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:23 volumio volumio[588]: info:
Apr 22 10:14:23 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:23 volumio volumio[588]: info:
Apr 22 10:14:23 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:23 volumio volumio[588]: info: sendMpdCommand status took 123 milliseconds
Apr 22 10:14:23 volumio volumio[588]: info: sendMpdCommand status took 33 milliseconds
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:23 volumio volumio[588]: info: sendMpdCommand status took 47 milliseconds
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:23 volumio volumio[588]: info: sendMpdCommand playlistinfo took 37 milliseconds
Apr 22 10:14:23 volumio volumio[588]: info: sendMpdCommand playlistinfo took 40 milliseconds
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:23 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"761 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:23 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:23 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:23 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48960,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"869 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:23 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:23 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: info: ------------------------------ 429ms
Apr 22 10:14:23 volumio volumio[588]: info: ------------------------------ 343ms
Apr 22 10:14:23 volumio volumio[588]: info: sendMpdCommand playlistinfo took 288 milliseconds
Apr 22 10:14:23 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:23 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:23 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48960,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"877 Kbps","isStreaming":false,"title":"Can't Get It Out Of My Head","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTcx","trackType":""}
Apr 22 10:14:23 volumio volumio[588]: verbose: CURRENT POSITION 1
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:23 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:23 volumio volumio[588]: info: ------------------------------ 527ms
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:23 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:23 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:24 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:24 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:24 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:29 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::ClearQueue
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::stPlaybackTimer
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::serviceStop
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::serviceStop
Apr 22 10:14:29 volumio volumio[588]: info: [1713780869561] ControllerUPNPBrowser::stop
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::clearPlayQueue
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::addQueueItems
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::addQueueItems
Apr 22 10:14:29 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d_757ed1a7
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d_757ed1a7
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:29 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:29 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPlay
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::play index 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::play index undefined
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: info: [1713780869735] ControllerUPNPBrowser::clearAddPlayTrack
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:14:29 volumio volumio[588]: info:
Apr 22 10:14:29 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:29 volumio volumio[588]: info: sendMpdCommand stop took 227 milliseconds
Apr 22 10:14:29 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:29 volumio volumio[588]: info: sendMpdCommand stop took 62 milliseconds
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand clear
Apr 22 10:14:29 volumio volumio[588]: info:
Apr 22 10:14:29 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:29 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:29 volumio volumio[588]: info:
Apr 22 10:14:29 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:29 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:29 volumio volumio[588]: info:
Apr 22 10:14:29 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:29 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:29 volumio volumio[588]: info: sendMpdCommand status took 51 milliseconds
Apr 22 10:14:29 volumio volumio[588]: info: sendMpdCommand clear took 46 milliseconds
Apr 22 10:14:29 volumio volumio[588]: info: sendMpdCommand status took 37 milliseconds
Apr 22 10:14:29 volumio volumio[588]: info: sendMpdCommand status took 29 milliseconds
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5"
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:29 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:29 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: 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 22 10:14:29 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:29 volumio volumio[588]: info: No code
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:29 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:29 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:29 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:29 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: 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 22 10:14:30 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: No code
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 345ms
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 354ms
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:30 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:30 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:30 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:30 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:30 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 388ms
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand playlistinfo took 368 milliseconds
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5"
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 56ms
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 54ms
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:30 volumio volumio[588]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 480ms
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:30 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5" took 86 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand play
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:30 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:30 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:30 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::ClearQueue
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::clearPlayQueue
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::addQueueItems
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::addQueueItems
Apr 22 10:14:30 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d_757ed1a7
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d_757ed1a7
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33972
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33971
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33981
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33978
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33980
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33970
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33977
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33973
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33975
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33976
Apr 22 10:14:30 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:30 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33974
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPlay
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::play index 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::play index undefined
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: [1713780870623] ControllerUPNPBrowser::clearAddPlayTrack
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 333ms
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand play took 320 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 311ms
Apr 22 10:14:30 volumio volumio[588]: info: ------------------------------ 298ms
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand stop took 113 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand status took 86 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand clear
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:30 volumio volumio[588]: info:
Apr 22 10:14:30 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:30 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand status took 71 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand status took 67 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand clear took 68 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand playlistinfo took 64 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand status took 46 milliseconds
Apr 22 10:14:30 volumio volumio[588]: info: sendMpdCommand status took 37 milliseconds
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5"
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:30 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:30 volumio volumio[588]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: 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 22 10:14:30 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: No code
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: 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 22 10:14:30 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:30 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:30 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:30 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: No code
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 374ms
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 318ms
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 314ms
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:31 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:31 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 352ms
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand playlistinfo took 327 milliseconds
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand playlistinfo took 326 milliseconds
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5"
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 66ms
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand status took 56 milliseconds
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 53ms
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand status took 43 milliseconds
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:31 volumio volumio[588]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 22 10:14:31 volumio volumio[588]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: 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 22 10:14:31 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: No code
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: 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 22 10:14:31 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: No code
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:31 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:31 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 577ms
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 576ms
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 267ms
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 256ms
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5" took 301 milliseconds
Apr 22 10:14:31 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand play
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 113ms
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand play took 103 milliseconds
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 362ms
Apr 22 10:14:31 volumio volumio[588]: info: ------------------------------ 311ms
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand status took 81 milliseconds
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: info: sendMpdCommand status took 80 milliseconds
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:31 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:31 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:14:31 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:14:31 volumio volumio[588]: info:
Apr 22 10:14:31 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:32 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand status took 149 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 192ms
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand status took 150 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 134 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 119 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 121ms
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand status took 93 milliseconds
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:32 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"494 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:32 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:14:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:32 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"494 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:32 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:32 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 432ms
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 453ms
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 291ms
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand status took 282 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 219 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 215 milliseconds
Apr 22 10:14:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 201 milliseconds
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:32 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:32 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:32 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:32 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"505 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:32 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:32 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:32 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:32 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:32 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 935ms
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 943ms
Apr 22 10:14:32 volumio volumio[588]: info: ------------------------------ 865ms
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:32 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:32 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:32 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: sendMpdCommand playlistinfo took 1025 milliseconds
Apr 22 10:14:33 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:33 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:33 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:33 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:33 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:33 volumio volumio[588]: info: ------------------------------ 1476ms
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:33 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:33 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::volumioSeek
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::seek
Apr 22 10:14:36 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:36 volumio volumio[588]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","realUri":"upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979","type":"song","albumart":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5.jpg?albumArt=true","artist":"Electric Light Orchestra","album":"Eldorado","name":"Eldorado Finale","title":"Eldorado Finale","duration":94}
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:36 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:36 volumio volumio[588]: info: [1713780876639] ControllerUPNPBrowser::seek
Apr 22 10:14:36 volumio volumio[588]: info: ControllerMpd::seek
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:36 volumio volumio[588]: error: null
Apr 22 10:14:36 volumio volumio[588]: info:
Apr 22 10:14:36 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:36 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:36 volumio volumio[588]: info:
Apr 22 10:14:36 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:36 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:36 volumio volumio[588]: info:
Apr 22 10:14:36 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:36 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:36 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:36 volumio volumio[588]: info: sendMpdCommand status took 86 milliseconds
Apr 22 10:14:36 volumio volumio[588]: info: sendMpdCommand status took 72 milliseconds
Apr 22 10:14:36 volumio volumio[588]: info: sendMpdCommand status took 59 milliseconds
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:36 volumio volumio[588]: info: sendMpdCommand playlistinfo took 36 milliseconds
Apr 22 10:14:36 volumio volumio[588]: info: sendMpdCommand playlistinfo took 42 milliseconds
Apr 22 10:14:36 volumio volumio[588]: info: sendMpdCommand playlistinfo took 40 milliseconds
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:36 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:36 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:36 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:36 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7960,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:36 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:36 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:36 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:36 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:37 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:37 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7960,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:37 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:37 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:37 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:37 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7960,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"743 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:37 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:37 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:37 volumio volumio[588]: info: ------------------------------ 702ms
Apr 22 10:14:37 volumio volumio[588]: info: ------------------------------ 683ms
Apr 22 10:14:37 volumio volumio[588]: info: ------------------------------ 685ms
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:37 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:37 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioSeek
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::seek
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:38 volumio volumio[588]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","realUri":"upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979","type":"song","albumart":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5.jpg?albumArt=true","artist":"Electric Light Orchestra","album":"Eldorado","name":"Eldorado Finale","title":"Eldorado Finale","duration":94}
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:38 volumio volumio[588]: info: [1713780878325] ControllerUPNPBrowser::seek
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::seek
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: error: null
Apr 22 10:14:38 volumio volumio[588]: info:
Apr 22 10:14:38 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:38 volumio volumio[588]: info:
Apr 22 10:14:38 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:38 volumio volumio[588]: info:
Apr 22 10:14:38 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:38 volumio volumio[588]: info: sendMpdCommand status took 93 milliseconds
Apr 22 10:14:38 volumio volumio[588]: info: sendMpdCommand status took 96 milliseconds
Apr 22 10:14:38 volumio volumio[588]: info: sendMpdCommand status took 85 milliseconds
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:38 volumio volumio[588]: info: sendMpdCommand playlistinfo took 41 milliseconds
Apr 22 10:14:38 volumio volumio[588]: info: sendMpdCommand playlistinfo took 51 milliseconds
Apr 22 10:14:38 volumio volumio[588]: info: sendMpdCommand playlistinfo took 47 milliseconds
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:38 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:38 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"818 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:38 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:38 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:38 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"818 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:38 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:38 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:38 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:38 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"818 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:38 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:38 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:38 volumio volumio[588]: info: ------------------------------ 564ms
Apr 22 10:14:38 volumio volumio[588]: info: ------------------------------ 561ms
Apr 22 10:14:38 volumio volumio[588]: info: ------------------------------ 555ms
Apr 22 10:14:38 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:39 volumio volumio[588]: info: CoreCommandRouter::volumioSeek
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::seek
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:40 volumio volumio[588]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","realUri":"upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_c49373a_be6367d-33979","type":"song","albumart":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5.jpg?albumArt=true","artist":"Electric Light Orchestra","album":"Eldorado","name":"Eldorado Finale","title":"Eldorado Finale","duration":94}
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:40 volumio volumio[588]: info: [1713780880002] ControllerUPNPBrowser::seek
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::seek
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: error: null
Apr 22 10:14:40 volumio volumio[588]: info:
Apr 22 10:14:40 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:40 volumio volumio[588]: info:
Apr 22 10:14:40 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:40 volumio volumio[588]: info:
Apr 22 10:14:40 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: sendMpdCommand status took 90 milliseconds
Apr 22 10:14:40 volumio volumio[588]: info: sendMpdCommand status took 82 milliseconds
Apr 22 10:14:40 volumio volumio[588]: info: sendMpdCommand status took 71 milliseconds
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:14:40 volumio volumio[588]: info: sendMpdCommand playlistinfo took 25 milliseconds
Apr 22 10:14:40 volumio volumio[588]: info: sendMpdCommand playlistinfo took 23 milliseconds
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:40 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17000,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"931 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:40 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:40 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:40 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17000,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"931 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:40 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:40 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: info: ------------------------------ 416ms
Apr 22 10:14:40 volumio volumio[588]: info: ------------------------------ 414ms
Apr 22 10:14:40 volumio volumio[588]: info: sendMpdCommand playlistinfo took 296 milliseconds
Apr 22 10:14:40 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:14:40 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:14:40 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17000,"duration":94,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"931 Kbps","isStreaming":false,"title":"Eldorado Finale","artist":"Electric Light Orchestra","album":"Eldorado","uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfYzQ5MzczYV9iZTYzNjdkLTMzOTc5","trackType":""}
Apr 22 10:14:40 volumio volumio[588]: verbose: CURRENT POSITION 4
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:14:40 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:14:40 volumio volumio[588]: info: ------------------------------ 528ms
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreCommandRouter::volumioGetQueue
Apr 22 10:14:40 volumio volumio[588]: info: CoreStateMachine::getQueue
Apr 22 10:14:40 volumio volumio[588]: info: CorePlayQueue::getQueue
Apr 22 10:14:52 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 10:14:52 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:54 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 10:14:54 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:14:54 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_20ac3911_34bed0b3-36780
Apr 22 10:14:54 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_20ac3911_34bed0b3-36780 in service upnp_browser
Apr 22 10:14:55 volumio volumio[588]: info: No valid Plugin REST Endpoint
Apr 22 10:15:03 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::ClearQueue
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::stPlaybackTimer
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::serviceStop
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrack 4
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::serviceStop
Apr 22 10:15:03 volumio volumio[588]: info: [1713780903759] ControllerUPNPBrowser::stop
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::clearPlayQueue
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::addQueueItems
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::addQueueItems
Apr 22 10:15:03 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:03 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_20ac3911_34bed0b3-36780
Apr 22 10:15:03 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_20ac3911_34bed0b3-36780
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::volumioPlay
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::play index 0
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::play index undefined
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::startPlaybackTimer
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:03 volumio volumio[588]: info: [1713780903855] ControllerUPNPBrowser::clearAddPlayTrack
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:15:03 volumio volumio[588]: info:
Apr 22 10:15:03 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:03 volumio volumio[588]: info: sendMpdCommand stop took 126 milliseconds
Apr 22 10:15:03 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:03 volumio volumio[588]: info: sendMpdCommand stop took 36 milliseconds
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand clear
Apr 22 10:15:03 volumio volumio[588]: info:
Apr 22 10:15:03 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:03 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:03 volumio volumio[588]: info:
Apr 22 10:15:03 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:03 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:03 volumio volumio[588]: info:
Apr 22 10:15:03 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:03 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:03 volumio volumio[588]: info: sendMpdCommand status took 46 milliseconds
Apr 22 10:15:03 volumio volumio[588]: info: sendMpdCommand clear took 42 milliseconds
Apr 22 10:15:03 volumio volumio[588]: info: sendMpdCommand status took 32 milliseconds
Apr 22 10:15:03 volumio volumio[588]: info: sendMpdCommand status took 23 milliseconds
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA"
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:03 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:03 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:03 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:03 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:03 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: 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 22 10:15:04 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:04 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:04 volumio volumio[588]: info: No code
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:04 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:04 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:04 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:04 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:04 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: 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 22 10:15:04 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:04 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:04 volumio volumio[588]: info: No code
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:04 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:04 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 399ms
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 432ms
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:15:04 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:15:04 volumio volumio[588]: error: updateQueue error: null
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 478ms
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand playlistinfo took 459 milliseconds
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA"
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 64ms
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 52ms
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:04 volumio volumio[588]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 559ms
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand add "http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA" took 158 milliseconds
Apr 22 10:15:04 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand play
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 178ms
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand play took 158 milliseconds
Apr 22 10:15:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 139ms
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 155ms
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand status took 130 milliseconds
Apr 22 10:15:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand status took 94 milliseconds
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:04 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:04 volumio volumio[588]: info:
Apr 22 10:15:04 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:04 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:04 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand status took 134 milliseconds
Apr 22 10:15:04 volumio volumio[588]: info: ------------------------------ 138ms
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand status took 83 milliseconds
Apr 22 10:15:04 volumio volumio[588]: info: sendMpdCommand playlistinfo took 76 milliseconds
Apr 22 10:15:05 volumio volumio[588]: info: sendMpdCommand playlistinfo took 70 milliseconds
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:05 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4474,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:05 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:15:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:05 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":892,"duration":4474,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:05 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:15:05 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:05 volumio volumio[588]: info: ------------------------------ 435ms
Apr 22 10:15:05 volumio volumio[588]: info: ------------------------------ 367ms
Apr 22 10:15:05 volumio volumio[588]: info:
Apr 22 10:15:05 volumio volumio[588]: ---------------------------- MPD announces system playlist update
Apr 22 10:15:05 volumio volumio[588]: info: Ignoring MPD Status Update
Apr 22 10:15:05 volumio volumio[588]: info:
Apr 22 10:15:05 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:05 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:05 volumio volumio[588]: info: ------------------------------ 292ms
Apr 22 10:15:05 volumio volumio[588]: info: sendMpdCommand status took 269 milliseconds
Apr 22 10:15:05 volumio volumio[588]: info: sendMpdCommand playlistinfo took 230 milliseconds
Apr 22 10:15:05 volumio volumio[588]: info: sendMpdCommand playlistinfo took 227 milliseconds
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:05 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:05 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":4474,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1003 Kbps","isStreaming":false,"title":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:05 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:15:05 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:05 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:05 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:05 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":4474,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"969 Kbps","isStreaming":false,"title":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:05 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:15:05 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:05 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:05 volumio volumio[588]: info: ------------------------------ 932ms
Apr 22 10:15:05 volumio volumio[588]: info: ------------------------------ 890ms
Apr 22 10:15:06 volumio volumio[588]: info: ------------------------------ 854ms
Apr 22 10:15:06 volumio volumio[588]: info: sendMpdCommand status took 883 milliseconds
Apr 22 10:15:06 volumio volumio[588]: info: sendMpdCommand playlistinfo took 840 milliseconds
Apr 22 10:15:06 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:06 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:06 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:06 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:06 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:06 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":4474,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1029 Kbps","isStreaming":false,"title":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:06 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:15:06 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:06 volumio volumio[588]: info: ------------------------------ 1417ms
Apr 22 10:15:06 volumio volumio[588]: info: sendMpdCommand playlistinfo took 274 milliseconds
Apr 22 10:15:06 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:06 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:06 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:06 volumio volumio[588]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":4474,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:06 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::syncState stateService play
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus play
Apr 22 10:15:06 volumio volumio[588]: info: Received an update from plugin. extracting info from payload
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:06 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:06 volumio volumio[588]: info: ------------------------------ 1419ms
Apr 22 10:15:25 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 10:15:25 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:30 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 10:15:30 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:30 volumio volumio[588]: info: Preloading song: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a-46930
Apr 22 10:15:30 volumio volumio[588]: info: Exploding uri upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a-46930 in service upnp_browser
Apr 22 10:15:32 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::ClearQueue
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::stPlaybackTimer
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::serviceStop
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::serviceStop
Apr 22 10:15:32 volumio volumio[588]: info: [1713780932128] ControllerUPNPBrowser::stop
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand stop
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::clearPlayQueue
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::addQueueItems
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::addQueueItems
Apr 22 10:15:32 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:32 volumio volumio[588]: info: Adding Item to queue: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a_12585c5c
Apr 22 10:15:32 volumio volumio[588]: info: Exploding uri upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a_12585c5c in service upnp_browser
Apr 22 10:15:32 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a-46930
Apr 22 10:15:32 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a-46930
Apr 22 10:15:32 volumio volumio[588]: info:
Apr 22 10:15:32 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand stop took 91 milliseconds
Apr 22 10:15:32 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:32 volumio volumio[588]: info:
Apr 22 10:15:32 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:32 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:32 volumio volumio[588]: info:
Apr 22 10:15:32 volumio volumio[588]: ---------------------------- MPD announces state update: player
Apr 22 10:15:32 volumio volumio[588]: info: ControllerMpd::getState
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand status took 33 milliseconds
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand status took 23 milliseconds
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand status took 14 milliseconds
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::parseState
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 34 milliseconds
Apr 22 10:15:32 volumio volumio[588]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:32 volumio volumio[588]: verbose: ControllerMpd::parseTrackInfo
Apr 22 10:15:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: 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":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:32 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: No code
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: 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":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:32 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: No code
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: ControllerMpd::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::servicePushState
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: 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":"0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","artist":null,"album":null,"uri":"http://192.168.0.178:10243/WMPNSSv4/2675789965/0_MTRfNDNkMjBmMTVfN2RkYTJmZWFfMjBhYzM5MTFfMzRiZWQwYjMtMzY3ODA","trackType":""}
Apr 22 10:15:32 volumio volumio[588]: verbose: CURRENT POSITION 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::syncState stateService stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: No code
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::pushState
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 0
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushState
Apr 22 10:15:32 volumio volumio[588]: info: ------------------------------ 414ms
Apr 22 10:15:32 volumio volumio[588]: info: ------------------------------ 406ms
Apr 22 10:15:32 volumio volumio[588]: info: ------------------------------ 400ms
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:15:32 volumio volumio[588]: info: CoreCommandRouter::volumioPlay
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::play index 1
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::play index undefined
Apr 22 10:15:32 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:32 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:15:39 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:39 volumio volumio[588]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::ClearQueue
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:39 volumio volumio[588]: info: CorePlayQueue::clearPlayQueue
Apr 22 10:15:39 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:15:39 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::addQueueItems
Apr 22 10:15:39 volumio volumio[588]: info: CorePlayQueue::addQueueItems
Apr 22 10:15:39 volumio volumio[588]: info: Preload queue cleared
Apr 22 10:15:39 volumio volumio[588]: info: Adding Item to queue: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a_12585c5c
Apr 22 10:15:39 volumio volumio[588]: info: Using cached record of: upnp/folder/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a_12585c5c
Apr 22 10:15:39 volumio volumio[588]: info: Adding Item to queue: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a-46930
Apr 22 10:15:39 volumio volumio[588]: info: Using cached record of: upnp/http://192.168.0.178:2869/upnphost/udhisapi.dll?control=uuid:0eea20db-3ebb-4964-979f-375b59e1d376+urn:upnp-org:serviceId:ContentDirectory@14_43d20f15_7dda2fea_95e70317_d4cd230a-46930
Apr 22 10:15:39 volumio volumio[588]: info: CoreCommandRouter::volumioPushQueue
Apr 22 10:15:39 volumio volumio[588]: info: CorePlayQueue::saveQueue
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::updateTrackBlock
Apr 22 10:15:39 volumio volumio[588]: info: CorePlayQueue::getTrackBlock
Apr 22 10:15:39 volumio volumio[588]: info: CoreCommandRouter::volumioPlay
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::play index 1
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::stop
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::play index undefined
Apr 22 10:15:39 volumio volumio[588]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 22 10:15:39 volumio volumio[588]: info: CorePlayQueue::getTrack 1
Apr 22 10:15:46 volumio volumio[588]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 10:15:46 volumio volumio[588]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 10:15:46 volumio volumio[588]: TypeError: Cannot read property 'length' of undefined
Apr 22 10:15:46 volumio volumio[588]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Apr 22 10:15:46 volumio volumio[588]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Apr 22 10:15:46 volumio volumio[588]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Apr 22 10:15:46 volumio volumio[588]: at Parser.emit (events.js:315:20)
Apr 22 10:15:46 volumio volumio[588]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Apr 22 10:15:46 volumio volumio[588]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Apr 22 10:15:46 volumio volumio[588]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Apr 22 10:15:46 volumio volumio[588]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Apr 22 10:15:46 volumio volumio[588]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Apr 22 10:15:46 volumio volumio[588]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Apr 22 10:15:46 volumio volumio[588]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Apr 22 10:15:46 volumio volumio[588]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Apr 22 10:15:46 volumio volumio[588]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Apr 22 10:15:46 volumio volumio[588]: at IncomingMessage.emit (events.js:327:22)
Apr 22 10:15:46 volumio volumio[588]: at endReadableNT (internal/streams/readable.js:1327:12)
Apr 22 10:15:46 volumio volumio[588]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 22 10:15:46 volumio volumio[588]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 10:15:49 volumio sudo[17701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-22 10:14
Apr 22 10:15:49 volumio sudo[17701]: 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"