-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sat 2025-06-14 23:29:55 JST. -- Jun 14 23:28:08 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:28:08 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:28:10 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81458 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81448 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81455 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81447 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81446 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81461 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81454 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81452 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81450 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81449 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81470 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81453 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81467 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81464 Jun 14 23:28:10 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81451 Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81458 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81448 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81455 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81447 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81446 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81461 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81454 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81452 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81450 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81449 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81470 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81453 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81467 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81464 in service upnp_browser Jun 14 23:28:10 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81451 in service upnp_browser Jun 14 23:28:19 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::ClearQueue Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::stop Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::stPlaybackTimer Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:19 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:19 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::serviceStop Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::serviceStop Jun 14 23:28:19 volumio volumio[1031]: info: [1749911299023] ControllerUPNPBrowser::stop Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::clearPlayQueue Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::addQueueItems Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::addQueueItems Jun 14 23:28:19 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81458 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81458 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81448 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81448 Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPlay Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::play index 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::addQueueItems Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::addQueueItems Jun 14 23:28:19 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81455 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81455 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81447 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81447 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81446 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81446 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81461 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81461 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81454 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81454 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81452 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81452 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81450 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81450 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81449 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81449 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81470 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81470 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81453 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81453 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81467 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81467 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81464 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81464 Jun 14 23:28:19 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81451 Jun 14 23:28:19 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81451 Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::stop Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::play index undefined Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::startPlaybackTimer Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: info: [1749911299138] ControllerUPNPBrowser::clearAddPlayTrack Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:28:19 volumio volumio[1031]: info: Jun 14 23:28:19 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand stop took 181 milliseconds Jun 14 23:28:19 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand stop took 68 milliseconds Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 23:28:19 volumio volumio[1031]: info: Jun 14 23:28:19 volumio volumio[1031]: ---------------------------- MPD announces system playlist update Jun 14 23:28:19 volumio volumio[1031]: info: Ignoring MPD Status Update Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand status took 9 milliseconds Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand clear took 6 milliseconds Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a" Jun 14 23:28:19 volumio volumio[1031]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 14 23:28:19 volumio volumio[1031]: error: updateQueue error: null Jun 14 23:28:19 volumio volumio[1031]: info: ------------------------------ 15ms Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:19 volumio volumio[1031]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 14 23:28:19 volumio volumio[1031]: info: ------------------------------ 29ms Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a" Jun 14 23:28:19 volumio volumio[1031]: info: Jun 14 23:28:19 volumio volumio[1031]: ---------------------------- MPD announces system playlist update Jun 14 23:28:19 volumio volumio[1031]: info: Ignoring MPD Status Update Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand add "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a" took 6 milliseconds Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand play Jun 14 23:28:19 volumio volumio[1031]: info: ------------------------------ 12ms Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand play took 4 milliseconds Jun 14 23:28:19 volumio volumio[1031]: info: Jun 14 23:28:19 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:19 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:19 volumio volumio[1031]: info: Jun 14 23:28:19 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand status took 3 milliseconds Jun 14 23:28:19 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand status took 1 milliseconds Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:19 volumio volumio[1031]: info: ControllerMpd::pushState Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a","artist":null,"album":null,"uri":"http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a","trackType":"m4a"} Jun 14 23:28:19 volumio volumio[1031]: verbose: CURRENT POSITION 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus stop Jun 14 23:28:19 volumio volumio[1031]: info: ------------------------------ 12ms Jun 14 23:28:19 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 14 23:28:19 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:19 volumio volumio[1031]: info: ControllerMpd::pushState Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Jun 14 23:28:19 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:19 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a","artist":null,"album":null,"uri":"http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0OA.m4a","trackType":"m4a"} Jun 14 23:28:19 volumio volumio[1031]: verbose: CURRENT POSITION 1 Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:28:19 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:19 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:19 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:19 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:19 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:19 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:19 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:19 volumio volumio[1031]: info: ------------------------------ 97ms Jun 14 23:28:19 volumio volumio[1031]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 14 23:28:21 volumio volumio[1031]: info: Executing endpoint metavolumio Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 23:28:21 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::ClearQueue Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::stop Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::stPlaybackTimer Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:21 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:21 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::serviceStop Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::getTrack 1 Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::serviceStop Jun 14 23:28:21 volumio volumio[1031]: info: [1749911301968] ControllerUPNPBrowser::stop Jun 14 23:28:21 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::clearPlayQueue Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:21 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:21 volumio volumio[1031]: info: CoreStateMachine::addQueueItems Jun 14 23:28:21 volumio volumio[1031]: info: CorePlayQueue::addQueueItems Jun 14 23:28:21 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:21 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81458 Jun 14 23:28:21 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81458 Jun 14 23:28:21 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81448 Jun 14 23:28:21 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81448 Jun 14 23:28:21 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81455 Jun 14 23:28:21 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81455 Jun 14 23:28:21 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81447 Jun 14 23:28:21 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81447 Jun 14 23:28:21 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81446 Jun 14 23:28:21 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81446 Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioPlay Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::play index 4 Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::addQueueItems Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::addQueueItems Jun 14 23:28:22 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81461 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81461 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81454 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81454 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81452 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81452 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81450 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81450 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81449 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81449 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81470 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81470 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81453 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81453 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81467 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81467 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81464 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81464 Jun 14 23:28:22 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81451 Jun 14 23:28:22 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_2531fe67_275f81d-81451 Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::stop Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::play index undefined Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::startPlaybackTimer Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:22 volumio volumio[1031]: info: [1749911302096] ControllerUPNPBrowser::clearAddPlayTrack Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:28:22 volumio volumio[1031]: info: Jun 14 23:28:22 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand stop took 157 milliseconds Jun 14 23:28:22 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand stop took 31 milliseconds Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 23:28:22 volumio volumio[1031]: info: Jun 14 23:28:22 volumio volumio[1031]: ---------------------------- MPD announces system playlist update Jun 14 23:28:22 volumio volumio[1031]: info: Ignoring MPD Status Update Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand status took 44 milliseconds Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand clear took 43 milliseconds Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a" Jun 14 23:28:22 volumio volumio[1031]: error: updateQueue error: null Jun 14 23:28:22 volumio volumio[1031]: info: ------------------------------ 11ms Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:22 volumio volumio[1031]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 14 23:28:22 volumio volumio[1031]: info: ------------------------------ 58ms Jun 14 23:28:22 volumio volumio[1031]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a" Jun 14 23:28:22 volumio volumio[1031]: info: Jun 14 23:28:22 volumio volumio[1031]: ---------------------------- MPD announces system playlist update Jun 14 23:28:22 volumio volumio[1031]: info: Ignoring MPD Status Update Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand add "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a" took 4 milliseconds Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand play Jun 14 23:28:22 volumio volumio[1031]: info: ------------------------------ 10ms Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand play took 3 milliseconds Jun 14 23:28:22 volumio volumio[1031]: info: Jun 14 23:28:22 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:22 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:22 volumio volumio[1031]: info: Jun 14 23:28:22 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand status took 5 milliseconds Jun 14 23:28:22 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand status took 3 milliseconds Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:22 volumio volumio[1031]: info: ControllerMpd::pushState Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:22 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a","artist":null,"album":null,"uri":"http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a","trackType":"m4a"} Jun 14 23:28:22 volumio volumio[1031]: verbose: CURRENT POSITION 4 Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus stop Jun 14 23:28:22 volumio volumio[1031]: info: ------------------------------ 19ms Jun 14 23:28:22 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 14 23:28:22 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:22 volumio volumio[1031]: info: ControllerMpd::pushState Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Jun 14 23:28:22 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:22 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a","artist":null,"album":null,"uri":"http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfMjUzMWZlNjdfMjc1ZjgxZC04MTQ0Ng.m4a","trackType":"m4a"} Jun 14 23:28:22 volumio volumio[1031]: verbose: CURRENT POSITION 4 Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:28:22 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:22 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:22 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:22 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:22 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:22 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:22 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:22 volumio volumio[1031]: info: ------------------------------ 135ms Jun 14 23:28:22 volumio volumio[1031]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 14 23:28:24 volumio volumio[1031]: info: Executing endpoint metavolumio Jun 14 23:28:24 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 23:28:26 volumio volumio[1031]: error: Failed request for metavolumio API Jun 14 23:28:30 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:28:31 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:32 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:28:32 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81199 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81201 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81203 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81200 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81195 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81204 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81198 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81202 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81196 Jun 14 23:28:32 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81197 Jun 14 23:28:32 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81199 in service upnp_browser Jun 14 23:28:32 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81201 in service upnp_browser Jun 14 23:28:32 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81203 in service upnp_browser Jun 14 23:28:32 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81200 in service upnp_browser Jun 14 23:28:32 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81195 in service upnp_browser Jun 14 23:28:32 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81204 in service upnp_browser Jun 14 23:28:33 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81198 in service upnp_browser Jun 14 23:28:33 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81202 in service upnp_browser Jun 14 23:28:33 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81196 in service upnp_browser Jun 14 23:28:33 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_f05b1f84_55d6a725-81197 in service upnp_browser Jun 14 23:28:37 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:28:37 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:39 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:28:39 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81029 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81025 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81022 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81027 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81026 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81024 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81016 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81021 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81020 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81019 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81023 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81017 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81018 Jun 14 23:28:39 volumio volumio[1031]: info: Preloading song: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81028 Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81029 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81025 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81022 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81027 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81026 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81024 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81016 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81021 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81020 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81019 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81023 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81017 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81018 in service upnp_browser Jun 14 23:28:39 volumio volumio[1031]: info: Exploding uri upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81028 in service upnp_browser Jun 14 23:28:47 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::ClearQueue Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::stop Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::stPlaybackTimer Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:47 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:47 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::serviceStop Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrack 4 Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::serviceStop Jun 14 23:28:47 volumio volumio[1031]: info: [1749911327616] ControllerUPNPBrowser::stop Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::clearPlayQueue Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::addQueueItems Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::addQueueItems Jun 14 23:28:47 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81029 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81029 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81025 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81025 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81022 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81022 Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioPlay Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::play index 2 Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::addQueueItems Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::addQueueItems Jun 14 23:28:47 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81027 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81027 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81026 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81026 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81024 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81024 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81016 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81016 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81021 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81021 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81020 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81020 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81019 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81019 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81023 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81023 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81017 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81017 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81018 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81018 Jun 14 23:28:47 volumio volumio[1031]: info: Adding Item to queue: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81028 Jun 14 23:28:47 volumio volumio[1031]: info: Using cached record of: upnp/http://192.168.100.18:2869/upnphost/udhisapi.dll?control=uuid:63898402-cc59-44e9-a9eb-855965f2cde1+urn:upnp-org:serviceId:ContentDirectory@14_637eefd4_f5cea018_4837811f_d842f994_4e67b8c-81028 Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::stop Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:47 volumio volumio[1031]: info: CoreCommandRouter::volumioPushQueue Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::saveQueue Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::play index undefined Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::updateTrackBlock Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrackBlock Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrack 2 Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::startPlaybackTimer Jun 14 23:28:47 volumio volumio[1031]: info: CorePlayQueue::getTrack 2 Jun 14 23:28:47 volumio volumio[1031]: info: [1749911327710] ControllerUPNPBrowser::clearAddPlayTrack Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 23:28:47 volumio volumio[1031]: info: Jun 14 23:28:47 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand stop took 120 milliseconds Jun 14 23:28:47 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand stop took 28 milliseconds Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 23:28:47 volumio volumio[1031]: info: Jun 14 23:28:47 volumio volumio[1031]: ---------------------------- MPD announces system playlist update Jun 14 23:28:47 volumio volumio[1031]: info: Ignoring MPD Status Update Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand status took 37 milliseconds Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand clear took 36 milliseconds Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a" Jun 14 23:28:47 volumio volumio[1031]: error: updateQueue error: null Jun 14 23:28:47 volumio volumio[1031]: info: ------------------------------ 12ms Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:47 volumio volumio[1031]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 14 23:28:47 volumio volumio[1031]: info: ------------------------------ 52ms Jun 14 23:28:47 volumio volumio[1031]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a" Jun 14 23:28:47 volumio volumio[1031]: info: Jun 14 23:28:47 volumio volumio[1031]: ---------------------------- MPD announces system playlist update Jun 14 23:28:47 volumio volumio[1031]: info: Ignoring MPD Status Update Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand add "http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a" took 4 milliseconds Jun 14 23:28:47 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 23:28:47 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand play Jun 14 23:28:47 volumio volumio[1031]: info: ------------------------------ 6ms Jun 14 23:28:47 volumio volumio[1031]: info: sendMpdCommand play took 3 milliseconds Jun 14 23:28:48 volumio volumio[1031]: info: Jun 14 23:28:48 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:48 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:48 volumio volumio[1031]: info: Jun 14 23:28:48 volumio volumio[1031]: ---------------------------- MPD announces state update: player Jun 14 23:28:48 volumio volumio[1031]: info: sendMpdCommand status took 3 milliseconds Jun 14 23:28:48 volumio volumio[1031]: info: ControllerMpd::getState Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand status Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:48 volumio volumio[1031]: info: sendMpdCommand status took 3 milliseconds Jun 14 23:28:48 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::parseState Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:48 volumio volumio[1031]: info: ControllerMpd::pushState Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Jun 14 23:28:48 volumio volumio[1031]: info: CorePlayQueue::getTrack 2 Jun 14 23:28:48 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":137,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a","artist":null,"album":null,"uri":"http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a","trackType":"m4a"} Jun 14 23:28:48 volumio volumio[1031]: verbose: CURRENT POSITION 2 Jun 14 23:28:48 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Jun 14 23:28:48 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus stop Jun 14 23:28:48 volumio volumio[1031]: info: ------------------------------ 17ms Jun 14 23:28:48 volumio volumio[1031]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 14 23:28:48 volumio volumio[1031]: verbose: ControllerMpd::parseTrackInfo Jun 14 23:28:48 volumio volumio[1031]: info: ControllerMpd::pushState Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Jun 14 23:28:48 volumio volumio[1031]: info: CorePlayQueue::getTrack 2 Jun 14 23:28:48 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":137,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a","artist":null,"album":null,"uri":"http://192.168.100.18:10243/WMPNSSv4/570947146/0_MTRfNjM3ZWVmZDRfZjVjZWEwMThfNDgzNzgxMWZfZDg0MmY5OTRfNGU2N2I4Yy04MTAyMg.m4a","trackType":"m4a"} Jun 14 23:28:48 volumio volumio[1031]: verbose: CURRENT POSITION 2 Jun 14 23:28:48 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Jun 14 23:28:48 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Jun 14 23:28:48 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Jun 14 23:28:48 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:48 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:48 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:48 volumio volumio[1031]: info: CoreStateMachine::pushState Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Jun 14 23:28:48 volumio volumio[1031]: info: MRS: Pushing multiroomSync output update for this device Jun 14 23:28:48 volumio volumio[1031]: info: MRS: Pushing multiroomSync output Jun 14 23:28:48 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Jun 14 23:28:48 volumio volumio[1031]: info: ------------------------------ 112ms Jun 14 23:28:48 volumio volumio[1031]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 14 23:28:50 volumio volumio[1031]: info: Executing endpoint metavolumio Jun 14 23:28:50 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 23:29:32 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:32 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:38 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:39 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:39 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:48 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:48 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:49 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:49 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:50 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:50 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:52 volumio volumio[1031]: info: Preload queue cleared Jun 14 23:29:54 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 23:29:54 volumio volumio[1031]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:29:54 volumio volumio[1031]: TypeError: Cannot read property 'length' of undefined Jun 14 23:29:54 volumio volumio[1031]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 14 23:29:54 volumio volumio[1031]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 14 23:29:54 volumio volumio[1031]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 14 23:29:54 volumio volumio[1031]: at Parser.emit (events.js:315:20) Jun 14 23:29:54 volumio volumio[1031]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 14 23:29:54 volumio volumio[1031]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 14 23:29:54 volumio volumio[1031]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 14 23:29:54 volumio volumio[1031]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 14 23:29:54 volumio volumio[1031]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 14 23:29:54 volumio volumio[1031]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 14 23:29:54 volumio volumio[1031]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 14 23:29:54 volumio volumio[1031]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 14 23:29:54 volumio volumio[1031]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 14 23:29:54 volumio volumio[1031]: at IncomingMessage.emit (events.js:327:22) Jun 14 23:29:54 volumio volumio[1031]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 14 23:29:54 volumio volumio[1031]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 14 23:29:54 volumio volumio[1031]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:29:55 volumio sudo[12212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 23:28 Jun 14 23:29:55 volumio sudo[12212]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"