-- Logs begin at Fri 2024-10-04 21:29:44 CEST, end at Fri 2024-10-04 22:02:28 CEST. -- Oct 04 22:01:00 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:00 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:00 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:00 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:01 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:01 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:02 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:02 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:02 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:02 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:03 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:03 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:03 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:03 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:04 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:04 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:04 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:04 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:05 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:05 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:05 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:05 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:06 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:06 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:07 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:07 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:08 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:08 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:08 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:08 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:09 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:09 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:09 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:09 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:10 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:10 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:11 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:11 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:11 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:11 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:12 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:12 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:12 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:12 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:13 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:13 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:13 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:13 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:14 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:14 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 22:01:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 22:01:14 volumio volumio[910]: info: Discovery: Getting this device information Oct 04 22:01:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 22:01:15 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:15 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:15 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:15 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:16 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:16 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:16 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:16 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:17 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:17 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:18 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:18 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:18 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:18 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:19 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:19 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:19 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:19 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:20 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:20 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:21 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:21 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:21 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:21 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:22 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:22 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:22 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:22 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:23 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:23 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:23 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:23 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:24 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:24 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:24 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:24 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:25 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:25 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:25 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:25 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:25 volumio volumiossh-tunnel[7432]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 04 22:01:26 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:26 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:26 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:26 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:27 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:27 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:27 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:27 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:28 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:28 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:28 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:28 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:29 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:29 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:29 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:29 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:30 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:30 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:30 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:30 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:31 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:31 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:31 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:31 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:32 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:32 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:32 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:32 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:33 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:33 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:33 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:33 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:34 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:34 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:34 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:34 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:35 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:35 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:35 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:35 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:36 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:36 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:36 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:36 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:36 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:01:37 volumio volumio[910]: info: Preload queue cleared Oct 04 22:01:37 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:37 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:37 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:37 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:38 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:38 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:38 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:38 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:39 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:39 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:39 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:39 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:40 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:40 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:40 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:40 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:41 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:41 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:41 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:41 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:41 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:01:41 volumio volumio[910]: info: Preload queue cleared Oct 04 22:01:42 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:42 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:42 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:42 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:43 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:43 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:43 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:43 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:44 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:44 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 22:01:44 volumio volumio[910]: info: Discovery: Getting this device information Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 22:01:44 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:01:45 volumio volumio[910]: info: Preload queue cleared Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{AB7B72F2-EB31-4A69-8A5C-C68B08B94041}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BA91CA77-D736-4325-8AC4-C024767543B0}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{17E55E5A-6AAD-421A-A894-6FB33C43820E}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{090276F5-F357-4597-A1FD-94C5FB961A9F}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BDE88919-C44F-4EF7-80BB-D7026DE82D72}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D3EB70DB-2E50-4B77-9A20-2A19397E8385}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{E62E430A-B2A5-42D4-B42B-E4DDF3005C2B}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{B3F65CE8-09A4-4F3F-BDEB-703CE6787C0E}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{B2F9A903-0E14-4D7F-B06D-3831340C88F2}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{C51AF5F1-2D99-4BCA-9685-1391A582A18C}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BD8D48B9-F049-411D-87A1-71C8F796A6BC}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{A2748E17-504E-4B32-90F5-C5629E6D7DCD}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{6C47D093-EC5A-4F3A-8B7C-3B98AC274625}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{A0F933B7-5DBB-4A8D-96EB-8988B4026EDD}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{146F5992-7616-4581-8873-9EE2FCBB8912}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D0B15063-3907-4C04-B7A5-9E5222C46FFB}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{2B14A75D-C088-4A90-B925-BC6617D393DF}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{552C38B6-5600-4E47-B1A1-77663D290354}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D2E379CB-A8B4-440B-BDB9-3AF79A3790CF}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{16502FD5-160A-42C0-9979-F1B5D9DD66B5}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{6E72951C-53EB-493E-8615-49D2572EE5EE}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{83DA6F4D-3508-411E-9287-087CC346739F}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{2C8BA4AB-00FC-49F6-A28C-45AF8A3C5130}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{4624E63D-FABF-4C8F-942B-3A8A8C595B43}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{07ECB1D3-8A3E-447A-BB6C-AACEED986FF6}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{E4B117F4-3C9E-4436-87B7-1C3EEAFC8F8A}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{021D4568-B805-4BC4-975B-A8C8295C9751}.0.4 Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{AB7B72F2-EB31-4A69-8A5C-C68B08B94041}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BA91CA77-D736-4325-8AC4-C024767543B0}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:45 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:45 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:45 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{17E55E5A-6AAD-421A-A894-6FB33C43820E}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{090276F5-F357-4597-A1FD-94C5FB961A9F}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BDE88919-C44F-4EF7-80BB-D7026DE82D72}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D3EB70DB-2E50-4B77-9A20-2A19397E8385}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{E62E430A-B2A5-42D4-B42B-E4DDF3005C2B}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{B3F65CE8-09A4-4F3F-BDEB-703CE6787C0E}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{B2F9A903-0E14-4D7F-B06D-3831340C88F2}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{C51AF5F1-2D99-4BCA-9685-1391A582A18C}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BD8D48B9-F049-411D-87A1-71C8F796A6BC}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{A2748E17-504E-4B32-90F5-C5629E6D7DCD}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{6C47D093-EC5A-4F3A-8B7C-3B98AC274625}.0.4 in service upnp_browser Oct 04 22:01:45 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{A0F933B7-5DBB-4A8D-96EB-8988B4026EDD}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{146F5992-7616-4581-8873-9EE2FCBB8912}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D0B15063-3907-4C04-B7A5-9E5222C46FFB}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{2B14A75D-C088-4A90-B925-BC6617D393DF}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{552C38B6-5600-4E47-B1A1-77663D290354}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:46 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:46 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:46 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:46 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:46 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D2E379CB-A8B4-440B-BDB9-3AF79A3790CF}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{16502FD5-160A-42C0-9979-F1B5D9DD66B5}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{6E72951C-53EB-493E-8615-49D2572EE5EE}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{83DA6F4D-3508-411E-9287-087CC346739F}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:46 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:46 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:46 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{2C8BA4AB-00FC-49F6-A28C-45AF8A3C5130}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{4624E63D-FABF-4C8F-942B-3A8A8C595B43}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{07ECB1D3-8A3E-447A-BB6C-AACEED986FF6}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{E4B117F4-3C9E-4436-87B7-1C3EEAFC8F8A}.0.4 in service upnp_browser Oct 04 22:01:46 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{021D4568-B805-4BC4-975B-A8C8295C9751}.0.4 in service upnp_browser Oct 04 22:01:47 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:47 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:47 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:47 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:47 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:47 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:47 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:47 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:47 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:47 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:47 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:47 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:48 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:48 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:48 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:49 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:49 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:49 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:49 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:50 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:01:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:01:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:50 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:50 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:51 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:51 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:51 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:51 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:52 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:52 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:53 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:53 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:53 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:53 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:54 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:54 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:55 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:55 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:55 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:55 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:55 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:01:55 volumio volumio[910]: info: Preload queue cleared Oct 04 22:01:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:56 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:56 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:57 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:57 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:57 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:57 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:58 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:58 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:58 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:58 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:59 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:01:59 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:01:59 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:01:59 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:01:59 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:02:00 volumio volumio[910]: info: Preload queue cleared Oct 04 22:02:00 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:00 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:00 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:00 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:01 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:01 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:02 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:02 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:02 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:02 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:03 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:03 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:03 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:03 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:04 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:04 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:04 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:04 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:05 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:05 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:05 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:05 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:06 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:06 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:07 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:07 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:02:08 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:08 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:08 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:08 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:08 volumio volumio[910]: info: Preload queue cleared Oct 04 22:02:09 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:09 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:09 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:09 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:10 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:10 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:02:10 volumio volumio[910]: info: Preload queue cleared Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BA91CA77-D736-4325-8AC4-C024767543B0}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{090276F5-F357-4597-A1FD-94C5FB961A9F}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BDE88919-C44F-4EF7-80BB-D7026DE82D72}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{E62E430A-B2A5-42D4-B42B-E4DDF3005C2B}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{2B14A75D-C088-4A90-B925-BC6617D393DF}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{AB7B72F2-EB31-4A69-8A5C-C68B08B94041}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{17E55E5A-6AAD-421A-A894-6FB33C43820E}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D3EB70DB-2E50-4B77-9A20-2A19397E8385}.0.E7D400E1 Oct 04 22:02:10 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BA91CA77-D736-4325-8AC4-C024767543B0}.0.E7D400E1 in service upnp_browser Oct 04 22:02:10 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{090276F5-F357-4597-A1FD-94C5FB961A9F}.0.E7D400E1 in service upnp_browser Oct 04 22:02:10 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{BDE88919-C44F-4EF7-80BB-D7026DE82D72}.0.E7D400E1 in service upnp_browser Oct 04 22:02:11 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{E62E430A-B2A5-42D4-B42B-E4DDF3005C2B}.0.E7D400E1 in service upnp_browser Oct 04 22:02:11 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{2B14A75D-C088-4A90-B925-BC6617D393DF}.0.E7D400E1 in service upnp_browser Oct 04 22:02:11 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{AB7B72F2-EB31-4A69-8A5C-C68B08B94041}.0.E7D400E1 in service upnp_browser Oct 04 22:02:11 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{17E55E5A-6AAD-421A-A894-6FB33C43820E}.0.E7D400E1 in service upnp_browser Oct 04 22:02:11 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.140:2869/upnphost/udhisapi.dll?control=uuid:cfbfce5f-5949-47ea-b359-c551ba8cbb9f+urn:upnp-org:serviceId:ContentDirectory@{D3EB70DB-2E50-4B77-9A20-2A19397E8385}.0.E7D400E1 in service upnp_browser Oct 04 22:02:11 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:11 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:11 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:11 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:11 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:11 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Oct 04 22:02:11 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:11 volumio volumio[910]: info: Prefetching next song Oct 04 22:02:11 volumio volumio[910]: info: Doing Prefetch in UPNP Oct 04 22:02:11 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.140:10243/WMPNSSv4/2926722963/0_ezJDOEJBNEFCLTAwRkMtNDlGNi1BMjhDLTQ1QUY4QTNDNTEzMH0uMC5CRTY2NUJGQg.mp3" Oct 04 22:02:11 volumio volumio[910]: info: Oct 04 22:02:11 volumio volumio[910]: ---------------------------- MPD announces system playlist update Oct 04 22:02:11 volumio volumio[910]: info: Ignoring MPD Status Update Oct 04 22:02:11 volumio volumio[910]: info: sendMpdCommand add "http://192.168.1.140:10243/WMPNSSv4/2926722963/0_ezJDOEJBNEFCLTAwRkMtNDlGNi1BMjhDLTQ1QUY4QTNDNTEzMH0uMC5CRTY2NUJGQg.mp3" took 1 milliseconds Oct 04 22:02:11 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 04 22:02:11 volumio volumio[910]: info: ------------------------------ 2ms Oct 04 22:02:11 volumio volumio[910]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:12 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:12 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:12 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:13 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:13 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:13 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:13 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:13 volumio volumio[910]: info: Executing endpoint getSimilarAlbums Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 04 22:02:13 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:13 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:13 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:13 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:14 volumio volumio[910]: error: Failed request for metavolumio API Oct 04 22:02:14 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 22:02:14 volumio volumio[910]: info: Discovery: Getting this device information Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:14 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:14 volumio volumio[910]: error: Failed request for metavolumio API Oct 04 22:02:14 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:14 volumio volumio[910]: info: Oct 04 22:02:14 volumio volumio[910]: ---------------------------- MPD announces system playlist update Oct 04 22:02:14 volumio volumio[910]: info: Ignoring MPD Status Update Oct 04 22:02:14 volumio volumio[910]: info: Oct 04 22:02:14 volumio volumio[910]: ---------------------------- MPD announces state update: player Oct 04 22:02:14 volumio volumio[910]: info: ControllerMpd::getState Oct 04 22:02:14 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Oct 04 22:02:14 volumio volumio[910]: info: ------------------------------ 2ms Oct 04 22:02:14 volumio volumio[910]: info: sendMpdCommand status took 2 milliseconds Oct 04 22:02:14 volumio volumio[910]: verbose: ControllerMpd::parseState Oct 04 22:02:14 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 22:02:14 volumio volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 04 22:02:14 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Oct 04 22:02:14 volumio volumio[910]: info: ControllerMpd::pushState Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::servicePushState Oct 04 22:02:14 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Oct 04 22:02:14 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":201714,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Kastanek","artist":"Zivot v kostce","album":"Rock Zadubskyho Dzastyna","uri":"http://192.168.1.140:10243/WMPNSSv4/2926722963/0_ezA3RUNCMUQzLThBM0UtNDQ3QS1CQjZDLUFBQ0VFRDk4NkZGNn0uMC5CRTY2NUJGQg.mp3","trackType":"mp3"} Oct 04 22:02:14 volumio volumio[910]: verbose: CURRENT POSITION 2 Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Oct 04 22:02:14 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::pushState Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Oct 04 22:02:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Oct 04 22:02:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::pushState Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Oct 04 22:02:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Oct 04 22:02:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output Oct 04 22:02:14 volumio volumio[910]: info: ------------------------------ 20ms Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:14 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:14 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:14 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:14 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:15 volumio volumio[910]: info: Oct 04 22:02:15 volumio volumio[910]: ---------------------------- MPD announces system playlist update Oct 04 22:02:15 volumio volumio[910]: info: Ignoring MPD Status Update Oct 04 22:02:15 volumio volumio[910]: info: Oct 04 22:02:15 volumio volumio[910]: ---------------------------- MPD announces state update: player Oct 04 22:02:15 volumio volumio[910]: info: ControllerMpd::getState Oct 04 22:02:15 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Oct 04 22:02:15 volumio volumio[910]: info: ------------------------------ 4ms Oct 04 22:02:15 volumio volumio[910]: info: sendMpdCommand status took 1 milliseconds Oct 04 22:02:15 volumio volumio[910]: verbose: ControllerMpd::parseState Oct 04 22:02:15 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 22:02:15 volumio volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 04 22:02:15 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Oct 04 22:02:15 volumio volumio[910]: info: ControllerMpd::pushState Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::servicePushState Oct 04 22:02:15 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Oct 04 22:02:15 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":164,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kaci","artist":"Zivot v kostce","album":"Rock Zadubskyho Dzastyna","uri":"http://192.168.1.140:10243/WMPNSSv4/2926722963/0_ezJDOEJBNEFCLTAwRkMtNDlGNi1BMjhDLTQ1QUY4QTNDNTEzMH0uMC5CRTY2NUJGQg.mp3","trackType":"mp3"} Oct 04 22:02:15 volumio volumio[910]: verbose: CURRENT POSITION 2 Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Oct 04 22:02:15 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::pushState Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Oct 04 22:02:15 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Oct 04 22:02:15 volumio volumio[910]: info: MRS: Pushing multiroomSync output Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::pushState Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Oct 04 22:02:15 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Oct 04 22:02:15 volumio volumio[910]: info: MRS: Pushing multiroomSync output Oct 04 22:02:15 volumio volumio[910]: info: ------------------------------ 22ms Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:15 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:15 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:15 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:15 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:15 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:16 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Oct 04 22:02:16 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:16 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:16 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:16 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:16 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:16 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:16 volumio volumio[910]: info: CoreStateMachine::pushState Oct 04 22:02:16 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:16 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 22:02:16 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Oct 04 22:02:16 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Oct 04 22:02:16 volumio volumio[910]: info: MRS: Pushing multiroomSync output Oct 04 22:02:16 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:16 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:16 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:17 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:17 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:17 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:17 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:18 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:18 volumio volumio[910]: info: Executing endpoint metavolumio Oct 04 22:02:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 22:02:18 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:18 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:18 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:18 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:18 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:19 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:19 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:19 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:19 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:19 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:20 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:20 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:20 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:21 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:21 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:21 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:21 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:21 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:22 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:22 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:22 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:22 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:22 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:23 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:23 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:23 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:23 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:23 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:24 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:24 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:24 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:24 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:24 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:25 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:25 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:25 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:25 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:25 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:26 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:26 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:26 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:26 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:26 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:27 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Oct 04 22:02:27 volumio volumio[910]: info: CorePlayQueue::getTrack 3 Oct 04 22:02:27 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Oct 04 22:02:27 volumio volumio[910]: info: CoreStateMachine::getQueue Oct 04 22:02:27 volumio volumio[910]: info: CorePlayQueue::getQueue Oct 04 22:02:27 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 04 22:02:27 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 22:02:27 volumio volumio[910]: TypeError: Cannot read property 'length' of undefined Oct 04 22:02:27 volumio volumio[910]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 04 22:02:27 volumio volumio[910]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 04 22:02:27 volumio volumio[910]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 04 22:02:27 volumio volumio[910]: at Parser.emit (events.js:315:20) Oct 04 22:02:27 volumio volumio[910]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 04 22:02:27 volumio volumio[910]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 04 22:02:27 volumio volumio[910]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 04 22:02:27 volumio volumio[910]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 04 22:02:27 volumio volumio[910]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 04 22:02:27 volumio volumio[910]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 04 22:02:27 volumio volumio[910]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 04 22:02:27 volumio volumio[910]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 04 22:02:27 volumio volumio[910]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 04 22:02:27 volumio volumio[910]: at IncomingMessage.emit (events.js:327:22) Oct 04 22:02:27 volumio volumio[910]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 04 22:02:27 volumio volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 04 22:02:27 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 22:02:28 volumio sudo[7550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 22:01 Oct 04 22:02:28 volumio sudo[7550]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"