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