{"action":"created","issue":{"url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/issues/489","labels_url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/issues/489/labels{/name}","comments_url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/issues/489/comments","events_url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/issues/489/events","html_url":"https://github.com/UniversalMediaServer/UniversalMediaServer/pull/489","id":58618887,"number":489,"title":"Visibility debug","user":{"login":"skeptical","id":1594203,"avatar_url":"https://avatars.githubusercontent.com/u/1594203?v=3","gravatar_id":"","url":"https://api.github.com/users/skeptical","html_url":"https://github.com/skeptical","followers_url":"https://api.github.com/users/skeptical/followers","following_url":"https://api.github.com/users/skeptical/following{/other_user}","gists_url":"https://api.github.com/users/skeptical/gists{/gist_id}","starred_url":"https://api.github.com/users/skeptical/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/skeptical/subscriptions","organizations_url":"https://api.github.com/users/skeptical/orgs","repos_url":"https://api.github.com/users/skeptical/repos","events_url":"https://api.github.com/users/skeptical/events{/privacy}","received_events_url":"https://api.github.com/users/skeptical/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"milestone":null,"comments":30,"created_at":"2015-02-23T17:27:48Z","updated_at":"2015-03-18T15:25:27Z","closed_at":null,"pull_request":{"url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/pulls/489","html_url":"https://github.com/UniversalMediaServer/UniversalMediaServer/pull/489","diff_url":"https://github.com/UniversalMediaServer/UniversalMediaServer/pull/489.diff","patch_url":"https://github.com/UniversalMediaServer/UniversalMediaServer/pull/489.patch"},"body":"@SubJunk Just a temp branch to help debug the ums visibility issue.  If you could create a binary for affected users to try we'll at least get some more useful logs :-)."},"comment":{"url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/issues/comments/83020712","html_url":"https://github.com/UniversalMediaServer/UniversalMediaServer/pull/489#issuecomment-83020712","issue_url":"https://api.github.com/repos/UniversalMediaServer/UniversalMediaServer/issues/489","id":83020712,"user":{"login":"ExSport","id":1260408,"avatar_url":"https://avatars.githubusercontent.com/u/1260408?v=3","gravatar_id":"","url":"https://api.github.com/users/ExSport","html_url":"https://github.com/ExSport","followers_url":"https://api.github.com/users/ExSport/followers","following_url":"https://api.github.com/users/ExSport/following{/other_user}","gists_url":"https://api.github.com/users/ExSport/gists{/gist_id}","starred_url":"https://api.github.com/users/ExSport/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/ExSport/subscriptions","organizations_url":"https://api.github.com/users/ExSport/orgs","repos_url":"https://api.github.com/users/ExSport/repos","events_url":"https://api.github.com/users/ExSport/events{/privacy}","received_events_url":"https://api.github.com/users/ExSport/received_events","type":"User","site_admin":false},"created_at":"2015-03-18T15:25:27Z","updated_at":"2015-03-18T15:25:27Z","body":"@skeptical \r\n```\r\n...filtered...\r\nINFO  15:40:49.195 [PMS Listeners Stopper] --> Shutting down Router...\r\nDEBUG 15:40:49.195 [PMS Listeners Stopper] Disabling network services...\r\nDEBUG 15:40:49.195 [PMS Listeners Stopper] Stopping stream client connection management/pool\r\nDEBUG 15:40:49.196 [PMS Listeners Stopper] Stopping stream server on address: /192.168.1.3\r\nDEBUG 15:40:50.196 [PMS Listeners Stopper] Stopping multicast receiver on interface: NVIDIA nForce 10/100/1000 Mbps Ethernet \r\nDEBUG 15:40:50.197 [PMS Listeners Stopper] Stopping datagram I/O on address: /192.168.1.3\r\nINFO  15:43:09.796 [PMS Listeners Stopper] --> Shutting down Configuration...\r\nINFO  15:43:09.797 [PMS Listeners Stopper] <<< UPnP service shutdown completed\r\nDEBUG 15:43:09.798 [PMS Listeners Stopper] Forcing shutdown of all active processes\r\nINFO  15:43:09.798 [PMS Listeners Stopper] Stopping server on host 192.168.1.3 and port 5001...\r\n```\r\nCommenting Router Shutdown:\r\n```\r\nINFO  16:00:24.286 [PMS Listeners Stopper] --> Shutting down Router is commented...\r\nINFO  16:00:24.287 [PMS Listeners Stopper] --> Shutting down Configuration...\r\nINFO  16:00:24.287 [PMS Listeners Stopper] <<< UPnP service shutdown completed\r\nDEBUG 16:00:24.288 [PMS Listeners Stopper] Forcing shutdown of all active processes\r\nINFO  16:00:24.288 [PMS Listeners Stopper] Stopping server on host 192.168.1.3 and port 5001...\r\n====> 16:03:05 ==> JAVAW.EXE process disappeared, no new line in debug.log added\r\n```\r\nPassing Shutdown Router with exception:\r\n```\r\nINFO  16:06:36.023 [PMS Listeners Stopper] --> Shutting down Router...\r\nINFO  16:06:36.024 [PMS Listeners Stopper] ~~> Launching: getRouter().shutdown()\r\nINFO  16:06:36.026 [HTTP Server] No IP filter specified, access granted to /192.168.1.3\r\nTRACE 16:06:36.026 [HTTP Server] Receiving a request from: 192.168.1.3\r\nTRACE 16:06:36.027 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.3,port=56144,localport=5001]\r\nTRACE 16:06:36.028 [Request Handler] Received on socket: GET /description/fetch HTTP/1.1\r\nTRACE 16:06:36.028 [Request Handler] Received on socket: User-agent: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0 UMS/5.1.0\r\nTRACE 16:06:36.028 [Request Handler] Ignoring self-originating request from /192.168.1.3:56144\r\nTRACE 16:06:36.028 [Request Handler] Close connection\r\nTRACE 16:06:36.029 [Request Handler] Received on socket: GET /UPnP_AV_ConnectionManager_1.0.xml HTTP/1.1\r\nTRACE 16:06:36.029 [Request Handler] Received on socket: Accept: */*\r\nTRACE 16:06:36.029 [Request Handler] Received on socket: Host: 192.168.1.3:5001\r\nTRACE 16:06:36.029 [Request Handler] Received on socket: User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.029 [Request Handler] Received on socket: Connection: close\r\nDEBUG 16:06:36.029 [Request Handler] HTTP User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.029 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:06:36.029 [Request Handler] HTTP: UPnP_AV_ConnectionManager_1.0.xml / 0-0\r\nTRACE 16:06:36.029 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:06:36.029 [Request Handler] Wrote on socket: Content-Type: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.029 [Request Handler] Wrote on socket: Cache-Control: no-cache\r\nTRACE 16:06:36.030 [Request Handler] Wrote on socket: Expires: 0\r\nTRACE 16:06:36.030 [Request Handler] Wrote on socket: Accept-Ranges: bytes\r\nTRACE 16:06:36.030 [Request Handler] Wrote on socket: Connection: keep-alive\r\nTRACE 16:06:36.030 [HTTP Server] Receiving a request from: 192.168.1.3\r\nTRACE 16:06:36.030 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:06:36.030 [Request Handler] Available Content-Length: 6990\r\nTRACE 16:06:36.030 [Request Handler] Wrote on socket: Content-Length: 6990\r\nTRACE 16:06:36.030 [Request Handler] Wrote on socket: \r\nTRACE 16:06:36.030 [Request Handler] Sending stream: 6990 bytes of UPnP_AV_ConnectionManager_1.0.xml\r\nTRACE 16:06:36.030 [Request Handler] Close connection\r\nTRACE 16:06:36.031 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.3,port=56145,localport=5001]\r\nTRACE 16:06:36.032 [Request Handler] Received on socket: GET /description/fetch HTTP/1.1\r\nTRACE 16:06:36.032 [Request Handler] Received on socket: User-agent: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0 UMS/5.1.0\r\nTRACE 16:06:36.032 [Request Handler] Ignoring self-originating request from /192.168.1.3:56145\r\nTRACE 16:06:36.032 [Request Handler] Close connection\r\nTRACE 16:06:36.033 [cling-19] SEND-TCP (StreamRequestMessage) GET http://192.168.1.3:5001/description/fetch:\r\nUser-agent: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0 UMS/5.1.0\r\n\r\nDEBUG 16:06:36.034 [PMS Listeners Stopper] Disabling network services...\r\nDEBUG 16:06:36.034 [PMS Listeners Stopper] Stopping stream client connection management/pool\r\nDEBUG 16:06:36.034 [PMS Listeners Stopper] Stopping stream server on address: /192.168.1.3\r\nTRACE 16:06:36.042 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:06:36.043 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=49960,localport=5001]\r\nTRACE 16:06:36.043 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: POST /upnp/control/content_directory HTTP/1.1\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: Accept: */*\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: Host: 192.168.1.3:5001\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: SOAPACTION: \"urn:schemas-upnp-org:service:ContentDirectory:1#GetSystemUpdateID\"\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: Content-Type: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.052 [Request Handler] Received on socket: Content-Length: 293\r\nDEBUG 16:06:36.052 [Request Handler] HTTP User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.052 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:06:36.111 [UPNP-Alive] SEND-UDP ALIVE (OutgoingNotificationRequestRootDevice) NOTIFY: /239.255.255.250:1900\r\nTRACE 16:06:36.248 [Request Handler] HTTP: upnp/control/content_directory / 0-0\r\nTRACE 16:06:36.248 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:06:36.248 [Request Handler] Wrote on socket: CONTENT-TYPE: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.248 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:06:36.248 [Request Handler] Wrote on socket: Content-Length: 335\r\nTRACE 16:06:36.248 [Request Handler] Wrote on socket: \r\nTRACE 16:06:36.248 [Request Handler] Close connection\r\nTRACE 16:06:36.259 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:06:36.260 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=49961,localport=5001]\r\nTRACE 16:06:36.260 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:06:36.269 [Request Handler] Received on socket: POST /upnp/control/content_directory HTTP/1.1\r\nTRACE 16:06:36.269 [Request Handler] Received on socket: Accept: */*\r\nTRACE 16:06:36.269 [Request Handler] Received on socket: User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.269 [Request Handler] Received on socket: Host: 192.168.1.3:5001\r\nTRACE 16:06:36.270 [Request Handler] Received on socket: SOAPACTION: \"urn:schemas-upnp-org:service:ContentDirectory:1#GetSearchCapabilities\"\r\nTRACE 16:06:36.270 [Request Handler] Received on socket: Content-Type: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.270 [Request Handler] Received on socket: Content-Length: 301\r\nDEBUG 16:06:36.270 [Request Handler] HTTP User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.270 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:06:36.469 [Request Handler] HTTP: upnp/control/content_directory / 0-0\r\nTRACE 16:06:36.469 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:06:36.469 [Request Handler] Wrote on socket: CONTENT-TYPE: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.469 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:06:36.469 [Request Handler] Wrote on socket: Content-Length: 354\r\nTRACE 16:06:36.469 [Request Handler] Wrote on socket: \r\nTRACE 16:06:36.469 [Request Handler] Close connection\r\nTRACE 16:06:36.480 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:06:36.481 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=49962,localport=5001]\r\nTRACE 16:06:36.481 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: POST /upnp/control/content_directory HTTP/1.1\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: Accept: */*\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: Host: 192.168.1.3:5001\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: SOAPACTION: \"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities\"\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: Content-Type: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.490 [Request Handler] Received on socket: Content-Length: 297\r\nDEBUG 16:06:36.490 [Request Handler] HTTP User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.490 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:06:36.690 [Request Handler] HTTP: upnp/control/content_directory / 0-0\r\nTRACE 16:06:36.690 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:06:36.690 [Request Handler] Wrote on socket: CONTENT-TYPE: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.690 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:06:36.691 [Request Handler] Wrote on socket: Content-Length: 346\r\nTRACE 16:06:36.691 [Request Handler] Wrote on socket: \r\nTRACE 16:06:36.691 [Request Handler] Close connection\r\nTRACE 16:06:36.701 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:06:36.702 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=49963,localport=5001]\r\nTRACE 16:06:36.702 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:06:36.702 [Request Handler] Received on socket: SUBSCRIBE /upnp/event/content_directory HTTP/1.1\r\nTRACE 16:06:36.702 [Request Handler] Received on socket: HOST: 192.168.1.3:5001\r\nTRACE 16:06:36.702 [Request Handler] Received on socket: SID: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nTRACE 16:06:36.702 [Request Handler] Received on socket: TIMEOUT: Second-1800\r\nTRACE 16:06:36.702 [Request Handler] Received on socket: Connection: close\r\nTRACE 16:06:36.702 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:06:36.702 [Request Handler] HTTP: upnp/event/content_directory / 0-0\r\nTRACE 16:06:36.703 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:06:36.703 [Request Handler] Close connection\r\nTRACE 16:06:36.722 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:06:36.723 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=49964,localport=5001]\r\nTRACE 16:06:36.724 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:06:36.724 [Request Handler] Received on socket: HEAD /description/fetch HTTP/1.1\r\nTRACE 16:06:36.724 [Request Handler] Received on socket: Accept: */*\r\nTRACE 16:06:36.724 [Request Handler] Received on socket: Host: 192.168.1.3:5001\r\nTRACE 16:06:36.724 [Request Handler] Received on socket: User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.724 [Request Handler] Received on socket: X-RequestExtraMetadata: true\r\nTRACE 16:06:36.724 [Request Handler] Received on socket: getcontentFeatures.dlna.org: 1\r\nDEBUG 16:06:36.724 [Request Handler] HTTP User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:06:36.724 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:06:36.724 [Request Handler] HTTP: description/fetch / 0-0\r\nTRACE 16:06:36.724 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:06:36.724 [Request Handler] Wrote on socket: Content-Type: text/xml; charset=\"utf-8\"\r\nTRACE 16:06:36.724 [Request Handler] Wrote on socket: Cache-Control: no-cache\r\nTRACE 16:06:36.725 [Request Handler] Wrote on socket: Expires: 0\r\nTRACE 16:06:36.725 [Request Handler] Wrote on socket: Accept-Ranges: bytes\r\nTRACE 16:06:36.725 [Request Handler] Wrote on socket: Connection: keep-alive\r\nTRACE 16:06:36.726 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:06:36.726 [Request Handler] Available Content-Length: 3027\r\nTRACE 16:06:36.726 [Request Handler] Wrote on socket: Content-Length: 3027\r\nTRACE 16:06:36.726 [Request Handler] Wrote on socket: \r\nTRACE 16:06:36.726 [Request Handler] Sending stream: 0 bytes of description/fetch\r\nTRACE 16:06:36.726 [Request Handler] Close connection\r\nDEBUG 16:06:37.042 [PMS Listeners Stopper] Stopping multicast receiver on interface: NVIDIA nForce 10/100/1000 Mbps Ethernet \r\nDEBUG 16:06:37.043 [PMS Listeners Stopper] Stopping datagram I/O on address: /192.168.1.3\r\nINFO  16:06:42.113 [UPNP-Alive] Exception in thread \"UPNP-Alive\" java.lang.RuntimeException: Fatal error while executing protocol 'SendingNotificationAlive': org.fourthline.cling.transport.RouterException: Router wasn't available exclusively after waiting 6000ms, lock failed: ReadLock\r\nINFO  16:06:42.115 [UPNP-Alive] \tat org.fourthline.cling.protocol.SendingAsync.run(SendingAsync.java:60)\r\nINFO  16:06:42.118 [UPNP-Alive] \tat net.pms.network.UPNPControl.sendAlive(UPNPControl.java:1022)\r\nINFO  16:06:42.118 [UPNP-Alive] \tat net.pms.network.UPNPHelper$1.run(UPNPHelper.java:392)\r\nINFO  16:06:42.119 [UPNP-Alive] \tat java.lang.Thread.run(Unknown Source)\r\nINFO  16:06:42.120 [UPNP-Alive] Caused by: org.fourthline.cling.transport.RouterException: Router wasn't available exclusively after waiting 6000ms, lock failed: ReadLock\r\nINFO  16:06:42.120 [UPNP-Alive] \tat org.fourthline.cling.transport.RouterImpl.lock(RouterImpl.java:497)\r\nINFO  16:06:42.120 [UPNP-Alive] \tat org.fourthline.cling.transport.RouterImpl.lock(RouterImpl.java:510)\r\nINFO  16:06:42.121 [UPNP-Alive] \tat org.fourthline.cling.transport.RouterImpl.send(RouterImpl.java:305)\r\nINFO  16:06:42.122 [UPNP-Alive] \tat net.pms.network.UPNPControl$5$1.send(UPNPControl.java:931)\r\nINFO  16:06:42.122 [UPNP-Alive] \tat org.fourthline.cling.protocol.async.SendingNotification.sendMessages(SendingNotification.java:110)\r\nINFO  16:06:42.129 [UPNP-Alive] \tat org.fourthline.cling.protocol.async.SendingNotification.execute(SendingNotification.java:84)\r\nINFO  16:06:42.131 [UPNP-Alive] \tat org.fourthline.cling.protocol.async.SendingNotificationAlive.execute(SendingNotificationAlive.java:41)\r\nINFO  16:06:42.131 [UPNP-Alive] \tat org.fourthline.cling.protocol.SendingAsync.run(SendingAsync.java:54)\r\nINFO  16:06:42.133 [UPNP-Alive] \t... 3 more\r\nTRACE 16:07:16.468 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:07:16.469 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=50009,localport=5001]\r\nTRACE 16:07:16.469 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:07:16.469 [Request Handler] Received on socket: SUBSCRIBE /upnp/event/content_directory HTTP/1.1\r\nTRACE 16:07:16.469 [Request Handler] Received on socket: HOST: 192.168.1.3:5001\r\nTRACE 16:07:16.469 [Request Handler] Received on socket: CALLBACK: <http://192.168.1.6:9000/>\r\nTRACE 16:07:16.469 [Request Handler] Received on socket: NT: upnp:event\r\nTRACE 16:07:16.469 [Request Handler] Received on socket: TIMEOUT: Second-300\r\nTRACE 16:07:16.469 [Request Handler] Received on socket: Connection: close\r\nTRACE 16:07:16.469 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:07:16.469 [Request Handler] HTTP: upnp/event/content_directory / 0-0\r\nTRACE 16:07:16.469 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:07:16.469 [Request Handler] Wrote on socket: CONTENT-TYPE: text/xml; charset=\"utf-8\"\r\nTRACE 16:07:16.469 [Request Handler] Wrote on socket: Content-Length: 0\r\nTRACE 16:07:16.469 [Request Handler] Wrote on socket: Connection: close\r\nTRACE 16:07:16.469 [Request Handler] Wrote on socket: SID: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: Timeout: Second-1800\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: \r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: NOTIFY /upnp/event/content_directory HTTP/1.1\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: SID: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: SEQ: 0\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: NT: upnp:event\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: NTS: upnp:propchange\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: HOST: 192.168.1.6:9000\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: CONTENT-TYPE: text/xml; charset=\"utf-8\"\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: Content-Length: 307\r\nTRACE 16:07:16.470 [Request Handler] Wrote on socket: \r\nTRACE 16:07:16.471 [Request Handler] Close connection\r\nTRACE 16:08:48.479 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:08:48.479 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=50057,localport=5001]\r\nTRACE 16:08:48.479 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:08:48.489 [Request Handler] Received on socket: GET /description/fetch HTTP/1.1\r\nTRACE 16:08:48.489 [Request Handler] Received on socket: Accept: */*\r\nTRACE 16:08:48.489 [Request Handler] Received on socket: Host: 192.168.1.3:5001\r\nTRACE 16:08:48.489 [Request Handler] Received on socket: User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:08:48.489 [Request Handler] Received on socket: Connection: close\r\nDEBUG 16:08:48.489 [Request Handler] HTTP User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.7, Linux 3.12.6; x86_64) DLNADOC/1.50\r\nTRACE 16:08:48.489 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:08:48.489 [Request Handler] HTTP: description/fetch / 0-0\r\nTRACE 16:08:48.489 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:08:48.491 [Request Handler] Wrote on socket: Content-Type: text/xml; charset=\"utf-8\"\r\nTRACE 16:08:48.491 [Request Handler] Wrote on socket: Cache-Control: no-cache\r\nTRACE 16:08:48.491 [Request Handler] Wrote on socket: Expires: 0\r\nTRACE 16:08:48.491 [Request Handler] Wrote on socket: Accept-Ranges: bytes\r\nTRACE 16:08:48.491 [Request Handler] Wrote on socket: Connection: keep-alive\r\nTRACE 16:08:48.492 [Request Handler] Wrote on socket: Server: Windows_Server_2008_R2-amd64-6.1, UPnP/1.0, UMS/5.1.0\r\nTRACE 16:08:48.492 [Request Handler] Available Content-Length: 3027\r\nTRACE 16:08:48.492 [Request Handler] Wrote on socket: Content-Length: 3027\r\nTRACE 16:08:48.492 [Request Handler] Wrote on socket: \r\nTRACE 16:08:48.492 [Request Handler] Sending stream: 3027 bytes of description/fetch\r\nTRACE 16:08:48.493 [Request Handler] Close connection\r\nTRACE 16:08:48.503 [HTTP Server] Receiving a request from: 192.168.1.6\r\nTRACE 16:08:48.504 [Request Handler] Opened request handler on socket Socket[addr=/192.168.1.6,port=50058,localport=5001]\r\nTRACE 16:08:48.504 [Request Handler] Matched media renderer \"QNAP Twonky\" based on address /192.168.1.6\r\nTRACE 16:08:48.504 [Request Handler] Received on socket: SUBSCRIBE /upnp/event/content_directory HTTP/1.1\r\nTRACE 16:08:48.504 [Request Handler] Received on socket: HOST: 192.168.1.3:5001\r\nTRACE 16:08:48.504 [Request Handler] Received on socket: SID: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nTRACE 16:08:48.504 [Request Handler] Received on socket: TIMEOUT: Second-1800\r\nTRACE 16:08:48.504 [Request Handler] Received on socket: Connection: close\r\nTRACE 16:08:48.504 [Request Handler] Recognized media renderer: QNAP Twonky\r\nTRACE 16:08:48.504 [Request Handler] HTTP: upnp/event/content_directory / 0-0\r\nTRACE 16:08:48.504 [Request Handler] Wrote on socket: HTTP/1.1 200 OK\r\nTRACE 16:08:48.504 [Request Handler] Close connection\r\nINFO  16:09:05.296 [PMS Listeners Stopper] ~~> Finished: getRouter().shutdown()\r\nINFO  16:09:05.297 [PMS Listeners Stopper] --> Shutting down Configuration...\r\nINFO  16:09:05.297 [PMS Listeners Stopper] <<< UPnP service shutdown completed\r\nDEBUG 16:09:05.298 [PMS Listeners Stopper] Forcing shutdown of all active processes\r\nINFO  16:09:05.298 [PMS Listeners Stopper] Stopping server on host 192.168.1.3 and port 5001...\r\n```\r\nAnd one without exception:\r\n```\r\nDEBUG 16:14:31.414 [PMS Listeners Stopper] Stopping UPNP Services...\r\nINFO  16:14:31.415 [PMS Listeners Stopper] >>> Shutting down UPnP service...\r\nINFO  16:14:31.418 [PMS Listeners Stopper] --> Shutting down Registry...\r\nTRACE 16:14:31.522 [PMS Listeners Stopper] SEND-TCP (OutgoingUnsubscribeRequestMessage) UNSUBSCRIBE http://192.168.1.4:8080/RenderingControl/evt:\r\nSid: uuid:b2bba28a-e14b-1616-99cf-01822870fbe8\r\n\r\nTRACE 16:14:31.625 [PMS Listeners Stopper] SEND-TCP (OutgoingUnsubscribeRequestMessage) UNSUBSCRIBE http://192.168.1.4:8080/ConnectionManager/evt:\r\nSid: uuid:7b401ce6-8108-10ad-b2a3-d4bc0941c1cd\r\n\r\nTRACE 16:14:31.727 [PMS Listeners Stopper] SEND-TCP (OutgoingUnsubscribeRequestMessage) UNSUBSCRIBE http://192.168.1.4:8080/AVTransport/evt:\r\nSid: uuid:0bea9258-1295-1941-a381-14886eae55f1\r\n\r\nTRACE 16:14:31.774 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestRootDevice) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: upnp:rootdevice\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::upnp:rootdevice\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.775 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestUDN) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.775 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestDeviceType) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: urn:schemas-upnp-org:device:MediaServer:1\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::urn:schemas-upnp-org:device:MediaServer:1\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.775 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestServiceType) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: urn:schemas-upnp-org:service:ConnectionManager:1\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::urn:schemas-upnp-org:service:ConnectionManager:1\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.925 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestRootDevice) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: upnp:rootdevice\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::upnp:rootdevice\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.925 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestUDN) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.925 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestDeviceType) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: urn:schemas-upnp-org:device:MediaServer:1\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::urn:schemas-upnp-org:device:MediaServer:1\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:31.926 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestServiceType) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: urn:schemas-upnp-org:service:ConnectionManager:1\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::urn:schemas-upnp-org:service:ConnectionManager:1\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:32.076 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestRootDevice) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: upnp:rootdevice\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::upnp:rootdevice\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:32.076 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestUDN) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:32.076 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestDeviceType) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: urn:schemas-upnp-org:device:MediaServer:1\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::urn:schemas-upnp-org:device:MediaServer:1\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nTRACE 16:14:32.077 [PMS Listeners Stopper] SEND-UDP (OutgoingNotificationRequestServiceType) NOTIFY: /239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nLOCATION: http://192.168.1.3:5001/description/fetch\r\nNT: urn:schemas-upnp-org:service:ConnectionManager:1\r\nHOST: 239.255.255.250:1900\r\nNTS: ssdp:byebye\r\nUSN: uuid:a7101079-0506-45a1-a255-fb89349b9d24::urn:schemas-upnp-org:service:ConnectionManager:1\r\nSERVER: WindowsServer2008R2/6.1 UPnP/1.0 Cling/2.0\r\n\r\nINFO  16:14:32.227 [PMS Listeners Stopper] --> Shutting down Router...\r\nINFO  16:14:32.227 [PMS Listeners Stopper] ~~> Launching: getRouter().shutdown()\r\nDEBUG 16:14:32.228 [PMS Listeners Stopper] Disabling network services...\r\nDEBUG 16:14:32.228 [PMS Listeners Stopper] Stopping stream client connection management/pool\r\nDEBUG 16:14:32.228 [PMS Listeners Stopper] Stopping stream server on address: /192.168.1.3\r\nDEBUG 16:14:33.229 [PMS Listeners Stopper] Stopping multicast receiver on interface: NVIDIA nForce 10/100/1000 Mbps Ethernet \r\nDEBUG 16:14:33.230 [PMS Listeners Stopper] Stopping datagram I/O on address: /192.168.1.3\r\nINFO  16:16:33.296 [PMS Listeners Stopper] ~~> Finished: getRouter().shutdown()\r\nINFO  16:16:33.296 [PMS Listeners Stopper] --> Shutting down Configuration...\r\nINFO  16:16:33.297 [PMS Listeners Stopper] <<< UPnP service shutdown completed\r\nDEBUG 16:16:33.298 [PMS Listeners Stopper] Forcing shutdown of all active processes\r\nINFO  16:16:33.298 [PMS Listeners Stopper] Stopping server on host 192.168.1.3 and port 5001...\r\n```\r\nDoes UMS waits for UNSUBSCRIBE reply? In specs I saw server should send it more than once to the network due to UDP (if it will be lost) but without waiting for any reply.\r\nEDIT: Will test your new commit..."}}