Ticket #235: txt.txt

File txt.txt, 179.5 kB (added by tava, 3 years ago)

debug log

Line 
1 tava@kombinat /home/tava $ COHERENCE_DEBUG=*:5 coherence
2 WARN  coherence                   Jun 01 13:43:53  Coherence UPnP framework version 0.6.4 starting... (coherence/base.py:281)
3 INFO  coherence                   Jun 01 13:43:53  running on host: 192.168.1.222 (coherence/base.py:304)
4 INFO  msearch                     Jun 01 13:43:53  send out discovery for ssdp:all (coherence/upnp/core/msearch.py:57)
5 WARN  webserver                   Jun 01 13:43:53  Web UI not supported atm, will return with version 0.7.0 (coherence/base.py:88)
6 WARN  webserver                   Jun 01 13:43:53  WebServer on port 30020 ready (coherence/base.py:114)
7 INFO  coherence                   Jun 01 13:43:53  adding plugin 'FSStore' (coherence/base.py:393)
8 INFO  coherence                   Jun 01 13:43:53  Activating FSStore plugin as MediaServer... (coherence/base.py:406)
9 INFO  coherence                   Jun 01 13:43:53  adding plugin 'IRadioStore' (coherence/base.py:393)
10 INFO  coherence                   Jun 01 13:43:53  Activating IRadioStore plugin as MediaServer... (coherence/base.py:406)
11 INFO  controlpoint                Jun 01 13:43:53  Coherence UPnP ControlPoint starting... (coherence/upnp/devices/control_point.py:63)
12 INFO  event_server                Jun 01 13:43:53  EventServer ready... (coherence/upnp/core/event.py:36)
13 INFO  ssdp                        Jun 01 13:43:53  SSDP command M-SEARCH * - from 192.168.1.222:37610 (coherence/upnp/core/ssdp.py:91)
14 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'host': '239.255.255.250:1900', 'st': 'ssdp:all', 'mx': '5', 'man': '"ssdp:discover"'} (coherence/upnp/core/ssdp.py:92)
15 INFO  ssdp                        Jun 01 13:43:53  Discovery request from (192.168.1.222,37610) for ssdp:all (coherence/upnp/core/ssdp.py:184)
16 INFO  ssdp                        Jun 01 13:43:53  Discovery request for ssdp:all (coherence/upnp/core/ssdp.py:185)
17 INFO  ssdp                        Jun 01 13:43:53  SSDP command M-SEARCH * - from 192.168.1.222:37610 (coherence/upnp/core/ssdp.py:91)
18 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'host': '239.255.255.250:1900', 'st': 'ssdp:all', 'mx': '5', 'man': '"ssdp:discover"'} (coherence/upnp/core/ssdp.py:92)
19 INFO  ssdp                        Jun 01 13:43:53  Discovery request from (192.168.1.222,37610) for ssdp:all (coherence/upnp/core/ssdp.py:184)
20 INFO  ssdp                        Jun 01 13:43:53  Discovery request for ssdp:all (coherence/upnp/core/ssdp.py:185)
21 INFO  connection_manager_server   Jun 01 13:43:53  Add callback <bound method ConnectionManagerServer.upnp_PrepareForConnection of <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec>> for ConnectionManager/PrepareForConnection (coherence/upnp/core/service.py:719)
22 INFO  connection_manager_server   Jun 01 13:43:53  Add callback <bound method ConnectionManagerServer.upnp_ConnectionComplete of <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec>> for ConnectionManager/ConnectionComplete (coherence/upnp/core/service.py:719)
23 INFO  connection_manager_server   Jun 01 13:43:53  Add callback <bound method ConnectionManagerServer.upnp_GetCurrentConnectionInfo of <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec>> for ConnectionManager/GetCurrentConnectionInfo (coherence/upnp/core/service.py:719)
24 INFO  variable                    Jun 01 13:43:53  variable check for update'SourceProtocolInfo' '' <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec> (coherence/upnp/core/variable.py:80)
25 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SourceProtocolInfo' '' (coherence/upnp/core/variable.py:170)
26 INFO  variable                    Jun 01 13:43:53  variable check for update'SinkProtocolInfo' '' <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec> (coherence/upnp/core/variable.py:80)
27 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SinkProtocolInfo' '' (coherence/upnp/core/variable.py:170)
28 INFO  variable                    Jun 01 13:43:53  variable check for update'CurrentConnectionIDs' '' <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec> (coherence/upnp/core/variable.py:80)
29 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'CurrentConnectionIDs' '' (coherence/upnp/core/variable.py:170)
30 DEBUG fs_store                    Jun 01 13:43:53  walk '/home/tava/Bilder' (coherence/backends/fs_storage.py:569)
31 INFO  content_directory_server    Jun 01 13:43:53  Add callback <bound method ContentDirectoryServer.upnp_Browse of <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c>> for ContentDirectory/Browse (coherence/upnp/core/service.py:719)
32 DEBUG fs_store                    Jun 01 13:43:53  append '/home/tava/Bilder' <type 'str'> id: 1000 @ media (coherence/backends/fs_storage.py:619)
33 INFO  content_directory_server    Jun 01 13:43:53  Add callback <bound method ContentDirectoryServer.upnp_Search of <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c>> for ContentDirectory/Search (coherence/upnp/core/service.py:719)
34 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action CreateObject, action disabled (coherence/upnp/core/service.py:707)
35 WARN  fs_store                    Jun 01 13:43:53  path '/home/tava/Bilder' not available - ignored (coherence/backends/fs_storage.py:621)
36 DEBUG fs_store                    Jun 01 13:43:53 INFO  content_directory_server    Jun 01 13:43:53  walk '/home/tava/Videos' (coherence/backends/fs_storage.py:569)
37  ContentDirectory has a missing callback for optional action DestroyObject, action disabled (coherence/upnp/core/service.py:707)
38 INFO  content_directory_server    Jun 01 13:43:53 DEBUG fs_store                    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action UpdateObject, action disabled (coherence/upnp/core/service.py:707)
39  append '/home/tava/Videos' <type 'str'> id: 1000 @ media (coherence/backends/fs_storage.py:619)
40 INFO  content_directory_server    Jun 01 13:43:53 WARN  fs_store                    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action MoveObject, action disabled (coherence/upnp/core/service.py:707)
41  path '/home/tava/Videos' not available - ignored (coherence/backends/fs_storage.py:621)
42 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action ImportResource, action disabled (coherence/upnp/core/service.py:707)
43 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action ExportResource, action disabled (coherence/upnp/core/service.py:707)
44 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action DeleteResource, action disabled (coherence/upnp/core/service.py:707)
45 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action StopTransferResource, action disabled (coherence/upnp/core/service.py:707)
46 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action GetTransferProgress, action disabled (coherence/upnp/core/service.py:707)
47 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action CreateReference, action disabled (coherence/upnp/core/service.py:707)
48 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' '0' <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c> (coherence/upnp/core/variable.py:80)
49 INFO  variable                    Jun 01 13:43:53  variable updated'SystemUpdateID' 0 (coherence/upnp/core/variable.py:182)
50 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' 0 <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c> (coherence/upnp/core/variable.py:80)
51 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SystemUpdateID' 0 (coherence/upnp/core/variable.py:170)
52 INFO  variable                    Jun 01 13:43:53  variable check for update'ContainerUpdateIDs' '' <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c> (coherence/upnp/core/variable.py:80)
53 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'ContainerUpdateIDs' '' (coherence/upnp/core/variable.py:170)
54 INFO  service_server              Jun 01 13:43:53  Add callback <bound method FakeMediaReceiverRegistrarBackend.upnp_IsAuthorized of <coherence.upnp.services.servers.media_receiver_registrar_server.FakeMediaReceiverRegistrarBackend instance at 0x8d9d64c>> for X_MS_MediaReceiverRegistrar/IsAuthorized (coherence/upnp/core/service.py:719)
55 INFO  service_server              Jun 01 13:43:53  Add callback <bound method FakeMediaReceiverRegistrarBackend.upnp_RegisterDevice of <coherence.upnp.services.servers.media_receiver_registrar_server.FakeMediaReceiverRegistrarBackend instance at 0x8d9d64c>> for X_MS_MediaReceiverRegistrar/RegisterDevice (coherence/upnp/core/service.py:719)
56 INFO  service_server              Jun 01 13:43:53  Add callback <bound method FakeMediaReceiverRegistrarBackend.upnp_IsValidated of <coherence.upnp.services.servers.media_receiver_registrar_server.FakeMediaReceiverRegistrarBackend instance at 0x8d9d64c>> for X_MS_MediaReceiverRegistrar/IsValidated (coherence/upnp/core/service.py:719)
57 INFO  mediaserver                 Jun 01 13:43:53  ScheduledRecordingServerLookupError('missing callback',) (coherence/upnp/devices/media_server.py:574)
58 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' 1 <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c> (coherence/upnp/core/variable.py:80)
59 INFO  variable                    Jun 01 13:43:53  variable updated'SystemUpdateID' 1 (coherence/upnp/core/variable.py:182)
60 INFO  variable                    Jun 01 13:43:53  variable check for update'SourceProtocolInfo' ['http-get:*:audio/mpeg:*', 'http-get:*:audio/x-scpls:*', 'http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'http-get:*:audio/x-scpls:*'] <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec> (coherence/upnp/core/variable.py:80)
61 INFO  variable                    Jun 01 13:43:53  variable updated'SourceProtocolInfo' 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*' (coherence/upnp/core/variable.py:182)
62 INFO  mediaserver                 Jun 01 13:43:53  MediaServer register (coherence/upnp/devices/basics.py:234)
63 INFO  ssdp                        Jun 01 13:43:53  Registering upnp:rootdevice (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml) (coherence/upnp/core/ssdp.py:114)
64 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.582804, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:129)
65 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice (coherence/upnp/core/ssdp.py:219)
66 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'NT: upnp:rootdevice', '', ''] (coherence/upnp/core/ssdp.py:235)
67 INFO  ssdp                        Jun 01 13:43:53  Registering uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml) (coherence/upnp/core/ssdp.py:114)
68 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.583564, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca'} (coherence/upnp/core/ssdp.py:129)
69 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca (coherence/upnp/core/ssdp.py:219)
70 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'NT: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', '', ''] (coherence/upnp/core/ssdp.py:235)
71 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:device:MediaServer:2 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml) (coherence/upnp/core/ssdp.py:114)
72 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.583971, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:129)
73 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2 (coherence/upnp/core/ssdp.py:219)
74 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'NT: urn:schemas-upnp-org:device:MediaServer:2', '', ''] (coherence/upnp/core/ssdp.py:235)
75 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:device:MediaServer:1 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-1.xml) (coherence/upnp/core/ssdp.py:114)
76 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': True, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.584372, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:device:MediaServer:1'} (coherence/upnp/core/ssdp.py:129)
77 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ConnectionManager:2 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml) (coherence/upnp/core/ssdp.py:114)
78 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.584554, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:129)
79 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2 (coherence/upnp/core/ssdp.py:219)
80 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'NT: urn:schemas-upnp-org:service:ConnectionManager:2', '', ''] (coherence/upnp/core/ssdp.py:235)
81 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ConnectionManager:1 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-1.xml) (coherence/upnp/core/ssdp.py:114)
82 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': True, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.58496, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ConnectionManager:1'} (coherence/upnp/core/ssdp.py:129)
83 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ContentDirectory:2 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml) (coherence/upnp/core/ssdp.py:114)
84 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.5851431, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:129)
85 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2 (coherence/upnp/core/ssdp.py:219)
86 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'NT: urn:schemas-upnp-org:service:ContentDirectory:2', '', ''] (coherence/upnp/core/ssdp.py:235)
87 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ContentDirectory:1 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-1.xml) (coherence/upnp/core/ssdp.py:114)
88 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': True, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.5855429, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ContentDirectory:1'} (coherence/upnp/core/ssdp.py:129)
89 INFO  ssdp                        Jun 01 13:43:53  Registering urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml) (coherence/upnp/core/ssdp.py:114)
90 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.5857251, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:129)
91 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (coherence/upnp/core/ssdp.py:219)
92 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml', 'NT: urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', '', ''] (coherence/upnp/core/ssdp.py:235)
93 WARN  mediaserver                 Jun 01 13:43:53  InternetRadio MediaServer (IRadioStore'>) activated with 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca (coherence/upnp/devices/media_server.py:630)
94 INFO  connection_manager_server   Jun 01 13:43:53  Add callback <bound method ConnectionManagerServer.upnp_PrepareForConnection of <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c>> for ConnectionManager/PrepareForConnection (coherence/upnp/core/service.py:719)
95 INFO  connection_manager_server   Jun 01 13:43:53  Add callback <bound method ConnectionManagerServer.upnp_ConnectionComplete of <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c>> for ConnectionManager/ConnectionComplete (coherence/upnp/core/service.py:719)
96 INFO  connection_manager_server   Jun 01 13:43:53  Add callback <bound method ConnectionManagerServer.upnp_GetCurrentConnectionInfo of <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c>> for ConnectionManager/GetCurrentConnectionInfo (coherence/upnp/core/service.py:719)
97 INFO  variable                    Jun 01 13:43:53  variable check for update'SourceProtocolInfo' '' <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c> (coherence/upnp/core/variable.py:80)
98 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SourceProtocolInfo' '' (coherence/upnp/core/variable.py:170)
99 INFO  variable                    Jun 01 13:43:53  variable check for update'SinkProtocolInfo' '' <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c> (coherence/upnp/core/variable.py:80)
100 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SinkProtocolInfo' '' (coherence/upnp/core/variable.py:170)
101 INFO  variable                    Jun 01 13:43:53  variable check for update'CurrentConnectionIDs' '' <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c> (coherence/upnp/core/variable.py:80)
102 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'CurrentConnectionIDs' '' (coherence/upnp/core/variable.py:170)
103 INFO  content_directory_server    Jun 01 13:43:53  Add callback <bound method ContentDirectoryServer.upnp_Browse of <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c>> for ContentDirectory/Browse (coherence/upnp/core/service.py:719)
104 INFO  content_directory_server    Jun 01 13:43:53  Add callback <bound method ContentDirectoryServer.upnp_Search of <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c>> for ContentDirectory/Search (coherence/upnp/core/service.py:719)
105 INFO  content_directory_server    Jun 01 13:43:53  Add callback <bound method FSStore.upnp_CreateObject of FSStore'>> for ContentDirectory/CreateObject (coherence/upnp/core/service.py:719)
106 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action DestroyObject, action disabled (coherence/upnp/core/service.py:707)
107 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action UpdateObject, action disabled (coherence/upnp/core/service.py:707)
108 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action MoveObject, action disabled (coherence/upnp/core/service.py:707)
109 INFO  content_directory_server    Jun 01 13:43:53  Add callback <bound method FSStore.upnp_ImportResource of FSStore'>> for ContentDirectory/ImportResource (coherence/upnp/core/service.py:719)
110 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action ExportResource, action disabled (coherence/upnp/core/service.py:707)
111 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action DeleteResource, action disabled (coherence/upnp/core/service.py:707)
112 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action StopTransferResource, action disabled (coherence/upnp/core/service.py:707)
113 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action GetTransferProgress, action disabled (coherence/upnp/core/service.py:707)
114 INFO  content_directory_server    Jun 01 13:43:53  ContentDirectory has a missing callback for optional action CreateReference, action disabled (coherence/upnp/core/service.py:707)
115 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' '0' <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c> (coherence/upnp/core/variable.py:80)
116 INFO  variable                    Jun 01 13:43:53  variable updated'SystemUpdateID' 0 (coherence/upnp/core/variable.py:182)
117 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' 0 <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c> (coherence/upnp/core/variable.py:80)
118 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SystemUpdateID' 0 (coherence/upnp/core/variable.py:170)
119 INFO  variable                    Jun 01 13:43:53  variable check for update'ContainerUpdateIDs' '' <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c> (coherence/upnp/core/variable.py:80)
120 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'ContainerUpdateIDs' '' (coherence/upnp/core/variable.py:170)
121 INFO  service_server              Jun 01 13:43:53  Add callback <bound method FakeMediaReceiverRegistrarBackend.upnp_IsAuthorized of <coherence.upnp.services.servers.media_receiver_registrar_server.FakeMediaReceiverRegistrarBackend instance at 0x8daebcc>> for X_MS_MediaReceiverRegistrar/IsAuthorized (coherence/upnp/core/service.py:719)
122 INFO  service_server              Jun 01 13:43:53  Add callback <bound method FakeMediaReceiverRegistrarBackend.upnp_RegisterDevice of <coherence.upnp.services.servers.media_receiver_registrar_server.FakeMediaReceiverRegistrarBackend instance at 0x8daebcc>> for X_MS_MediaReceiverRegistrar/RegisterDevice (coherence/upnp/core/service.py:719)
123 INFO  service_server              Jun 01 13:43:53  Add callback <bound method FakeMediaReceiverRegistrarBackend.upnp_IsValidated of <coherence.upnp.services.servers.media_receiver_registrar_server.FakeMediaReceiverRegistrarBackend instance at 0x8daebcc>> for X_MS_MediaReceiverRegistrar/IsValidated (coherence/upnp/core/service.py:719)
124 INFO  mediaserver                 Jun 01 13:43:53  ScheduledRecordingServerLookupError('missing callback',) (coherence/upnp/devices/media_server.py:574)
125 INFO  variable                    Jun 01 13:43:53  variable check for update'SourceProtocolInfo' ['internal:192.168.1.222:audio/mpeg:*', 'http-get:*:audio/mpeg:*', 'internal:192.168.1.222:video/mp4:*', 'http-get:*:video/mp4:*', 'internal:192.168.1.222:application/ogg:*', 'http-get:*:application/ogg:*', 'internal:192.168.1.222:video/x-msvideo:*', 'http-get:*:video/x-msvideo:*', 'internal:192.168.1.222:video/mpeg:*', 'http-get:*:video/mpeg:*', 'internal:192.168.1.222:video/avi:*', 'http-get:*:video/avi:*', 'internal:192.168.1.222:video/quicktime:*', 'http-get:*:video/quicktime:*', 'internal:192.168.1.222:image/gif:*', 'http-get:*:image/gif:*', 'internal:192.168.1.222:image/jpeg:*', 'http-get:*:image/jpeg:*', 'internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'internal:192.168.1.222:application/ogg:*', 'http-get:*:application/ogg:*', 'internal:192.168.1.222:video/x-msvideo:*', 'http-get:*:video/x-msvideo:*', 'internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000', 'internal:192.168.1.222:video/avi:*', 'http-get:*:video/avi:*', 'internal:192.168.1.222:video/quicktime:*', 'http-get:*:video/quicktime:*', 'internal:192.168.1.222:image/gif:*', 'http-get:*:image/gif:*', 'internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000', 'http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000'] <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c> (coherence/upnp/core/variable.py:80)
126 INFO  variable                    Jun 01 13:43:53  variable updated'SourceProtocolInfo' 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000' (coherence/upnp/core/variable.py:182)
127 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' 0 <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c> (coherence/upnp/core/variable.py:80)
128 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SystemUpdateID' 0 (coherence/upnp/core/variable.py:170)
129 INFO  mediaserver                 Jun 01 13:43:53  MediaServer register (coherence/upnp/devices/basics.py:234)
130 INFO  ssdp                        Jun 01 13:43:53  Registering upnp:rootdevice (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml) (coherence/upnp/core/ssdp.py:114)
131 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.618588, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:129)
132 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice (coherence/upnp/core/ssdp.py:219)
133 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'NT: upnp:rootdevice', '', ''] (coherence/upnp/core/ssdp.py:235)
134 INFO  ssdp                        Jun 01 13:43:53  Registering uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml) (coherence/upnp/core/ssdp.py:114)
135 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.619097, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc'} (coherence/upnp/core/ssdp.py:129)
136 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc (coherence/upnp/core/ssdp.py:219)
137 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'NT: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', '', ''] (coherence/upnp/core/ssdp.py:235)
138 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:device:MediaServer:2 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml) (coherence/upnp/core/ssdp.py:114)
139 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.6194971, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:129)
140 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2 (coherence/upnp/core/ssdp.py:219)
141 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'NT: urn:schemas-upnp-org:device:MediaServer:2', '', ''] (coherence/upnp/core/ssdp.py:235)
142 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:device:MediaServer:1 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-1.xml) (coherence/upnp/core/ssdp.py:114)
143 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': True, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.619904, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:device:MediaServer:1'} (coherence/upnp/core/ssdp.py:129)
144 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ConnectionManager:2 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml) (coherence/upnp/core/ssdp.py:114)
145 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.62009, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:129)
146 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2 (coherence/upnp/core/ssdp.py:219)
147 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'NT: urn:schemas-upnp-org:service:ConnectionManager:2', '', ''] (coherence/upnp/core/ssdp.py:235)
148 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ConnectionManager:1 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-1.xml) (coherence/upnp/core/ssdp.py:114)
149 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': True, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.6204901, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ConnectionManager:1'} (coherence/upnp/core/ssdp.py:129)
150 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ContentDirectory:2 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml) (coherence/upnp/core/ssdp.py:114)
151 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.620676, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:129)
152 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2 (coherence/upnp/core/ssdp.py:219)
153 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'NT: urn:schemas-upnp-org:service:ContentDirectory:2', '', ''] (coherence/upnp/core/ssdp.py:235)
154 INFO  ssdp                        Jun 01 13:43:53  Registering urn:schemas-upnp-org:service:ContentDirectory:1 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-1.xml) (coherence/upnp/core/ssdp.py:114)
155 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': True, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.6210821, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:schemas-upnp-org:service:ContentDirectory:1'} (coherence/upnp/core/ssdp.py:129)
156 INFO  ssdp                        Jun 01 13:43:53  Registering urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml) (coherence/upnp/core/ssdp.py:114)
157 INFO  ssdp                        Jun 01 13:43:53  {'SILENT': False, 'USN': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'MANIFESTATION': 'local', 'HOST': '192.168.1.222', 'SERVER': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'last-seen': 1243856633.6212649, 'EXT': '', 'LOCATION': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml', 'CACHE-CONTROL': 'max-age=1800', 'ST': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:129)
158 INFO  ssdp                        Jun 01 13:43:53  Sending alive notification for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (coherence/upnp/core/ssdp.py:219)
159 DEBUG ssdp                        Jun 01 13:43:53  doNotify content['NOTIFY * HTTP/1.1', 'HOST: 239.255.255.250:1900', 'NTS: ssdp:alive', 'EXT: ', 'USN: uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'CACHE-CONTROL: max-age=1800', 'SERVER: Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'LOCATION: http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml', 'NT: urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', '', ''] (coherence/upnp/core/ssdp.py:235)
160 WARN  mediaserver                 Jun 01 13:43:53  HDD-Kombinat MediaServer (FSStore'>) activated with a8d9e91f-d924-427a-8bca-bf95ed2c11cc (coherence/upnp/devices/media_server.py:630)
161 INFO  coherence                   Jun 01 13:43:53  creating 'upnp:rootdevice' 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice' (coherence/base.py:582)
162 INFO  coherence                   Jun 01 13:43:53  creating upnp:rootdevice 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice' (coherence/base.py:584)
163 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
164 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:92)
165 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for upnp:rootdevice (coherence/upnp/core/ssdp.py:154)
166 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:155)
167 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice' (coherence/upnp/core/ssdp.py:160)
168 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
169 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:92)
170 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for upnp:rootdevice (coherence/upnp/core/ssdp.py:154)
171 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:155)
172 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice' (coherence/upnp/core/ssdp.py:160)
173 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
174 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca'} (coherence/upnp/core/ssdp.py:92)
175 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca (coherence/upnp/core/ssdp.py:154)
176 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca'} (coherence/upnp/core/ssdp.py:155)
177 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca' (coherence/upnp/core/ssdp.py:160)
178 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
179 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca'} (coherence/upnp/core/ssdp.py:92)
180 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca (coherence/upnp/core/ssdp.py:154)
181 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca'} (coherence/upnp/core/ssdp.py:155)
182 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca' (coherence/upnp/core/ssdp.py:160)
183 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
184 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:92)
185 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:device:MediaServer:2 (coherence/upnp/core/ssdp.py:154)
186 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:155)
187 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2' (coherence/upnp/core/ssdp.py:160)
188 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
189 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:92)
190 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:device:MediaServer:2 (coherence/upnp/core/ssdp.py:154)
191 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:155)
192 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:device:MediaServer:2' (coherence/upnp/core/ssdp.py:160)
193 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
194 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:92)
195 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ConnectionManager:2 (coherence/upnp/core/ssdp.py:154)
196 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:155)
197 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2' (coherence/upnp/core/ssdp.py:160)
198 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
199 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:92)
200 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ConnectionManager:2 (coherence/upnp/core/ssdp.py:154)
201 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:155)
202 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ConnectionManager:2' (coherence/upnp/core/ssdp.py:160)
203 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
204 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:92)
205 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ContentDirectory:2 (coherence/upnp/core/ssdp.py:154)
206 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:155)
207 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2' (coherence/upnp/core/ssdp.py:160)
208 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
209 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:92)
210 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ContentDirectory:2 (coherence/upnp/core/ssdp.py:154)
211 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:155)
212 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:schemas-upnp-org:service:ContentDirectory:2' (coherence/upnp/core/ssdp.py:160)
213 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
214 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:92)
215 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (coherence/upnp/core/ssdp.py:154)
216 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:155)
217 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1' (coherence/upnp/core/ssdp.py:160)
218 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
219 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:92)
220 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (coherence/upnp/core/ssdp.py:154)
221 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:155)
222 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1' (coherence/upnp/core/ssdp.py:160)
223 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
224 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:92)
225 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for upnp:rootdevice (coherence/upnp/core/ssdp.py:154)
226 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:155)
227 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice' (coherence/upnp/core/ssdp.py:160)
228 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
229 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:92)
230 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for upnp:rootdevice (coherence/upnp/core/ssdp.py:154)
231 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'upnp:rootdevice'} (coherence/upnp/core/ssdp.py:155)
232 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice' (coherence/upnp/core/ssdp.py:160)
233 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
234 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc'} (coherence/upnp/core/ssdp.py:92)
235 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc (coherence/upnp/core/ssdp.py:154)
236 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc'} (coherence/upnp/core/ssdp.py:155)
237 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc' (coherence/upnp/core/ssdp.py:160)
238 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
239 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc'} (coherence/upnp/core/ssdp.py:92)
240 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc (coherence/upnp/core/ssdp.py:154)
241 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc'} (coherence/upnp/core/ssdp.py:155)
242 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc' (coherence/upnp/core/ssdp.py:160)
243 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
244 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:92)
245 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:device:MediaServer:2 (coherence/upnp/core/ssdp.py:154)
246 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:155)
247 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2' (coherence/upnp/core/ssdp.py:160)
248 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
249 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:92)
250 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:device:MediaServer:2 (coherence/upnp/core/ssdp.py:154)
251 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:device:MediaServer:2'} (coherence/upnp/core/ssdp.py:155)
252 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:device:MediaServer:2' (coherence/upnp/core/ssdp.py:160)
253 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
254 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:92)
255 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ConnectionManager:2 (coherence/upnp/core/ssdp.py:154)
256 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:155)
257 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2' (coherence/upnp/core/ssdp.py:160)
258 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
259 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:92)
260 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ConnectionManager:2 (coherence/upnp/core/ssdp.py:154)
261 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ConnectionManager:2'} (coherence/upnp/core/ssdp.py:155)
262 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ConnectionManager:2' (coherence/upnp/core/ssdp.py:160)
263 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
264 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:92)
265 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ContentDirectory:2 (coherence/upnp/core/ssdp.py:154)
266 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:155)
267 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2' (coherence/upnp/core/ssdp.py:160)
268 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
269 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:92)
270 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:schemas-upnp-org:service:ContentDirectory:2 (coherence/upnp/core/ssdp.py:154)
271 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:schemas-upnp-org:service:ContentDirectory:2'} (coherence/upnp/core/ssdp.py:155)
272 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:schemas-upnp-org:service:ContentDirectory:2' (coherence/upnp/core/ssdp.py:160)
273 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
274 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:92)
275 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (coherence/upnp/core/ssdp.py:154)
276 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:155)
277 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1' (coherence/upnp/core/ssdp.py:160)
278 INFO  ssdp                        Jun 01 13:43:53  SSDP command NOTIFY * - from 192.168.1.222:1900 (coherence/upnp/core/ssdp.py:91)
279 DEBUG ssdp                        Jun 01 13:43:53  with headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:92)
280 INFO  ssdp                        Jun 01 13:43:53  Notification from (192.168.1.222,1900) for urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1 (coherence/upnp/core/ssdp.py:154)
281 DEBUG ssdp                        Jun 01 13:43:53  Notification headers:{'usn': 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1', 'nts': 'ssdp:alive', 'host': '239.255.255.250:1900', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'ext': '', 'location': 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/xbox-description-1.xml', 'cache-control': 'max-age=1800', 'nt': 'urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1'} (coherence/upnp/core/ssdp.py:155)
282 DEBUG ssdp                        Jun 01 13:43:53  updating last-seen for 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1' (coherence/upnp/core/ssdp.py:160)
283 INFO  coherence                   Jun 01 13:43:53  creating 'upnp:rootdevice' 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice' (coherence/base.py:582)
284 INFO  coherence                   Jun 01 13:43:53  creating upnp:rootdevice 'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice' (coherence/base.py:584)
285 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <GET /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml HTTP/1.1> (coherence/base.py:46)
286 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, description-2.xml, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml IPv4Address(TCP, '192.168.1.66', 61510) (coherence/upnp/devices/media_server.py:68)
287 INFO  mediaserver                 Jun 01 13:43:53  {'date': 'Mon, 01 Jun 2009 11:41:53 GMT', 'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'host': '192.168.1.222:30020', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0'} (coherence/upnp/devices/media_server.py:70)
288 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <GET /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml HTTP/1.0> (coherence/base.py:46)
289 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, description-2.xml, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml IPv4Address(TCP, '192.168.1.222', 52578) (coherence/upnp/devices/media_server.py:68)
290 INFO  mediaserver                 Jun 01 13:43:53  {'host': '192.168.1.222', 'user-agent': 'Twisted PageGetter'} (coherence/upnp/devices/media_server.py:70)
291 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <GET /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml HTTP/1.0> (coherence/base.py:46)
292 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, description-2.xml, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml IPv4Address(TCP, '192.168.1.222', 52580) (coherence/upnp/devices/media_server.py:68)
293 INFO  mediaserver                 Jun 01 13:43:53  {'host': '192.168.1.222', 'user-agent': 'Twisted PageGetter'} (coherence/upnp/devices/media_server.py:70)
294 DEBUG device                      Jun 01 13:43:53  got device description from 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/description-2.xml' (coherence/upnp/core/device.py:358)
295 INFO  service_client              Jun 01 13:43:53  InternetRadio urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager initialized (coherence/upnp/core/service.py:78)
296 DEBUG device                      Jun 01 13:43:53  add_service Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/device.py:95)
297 INFO  service_client              Jun 01 13:43:53  InternetRadio urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory initialized (coherence/upnp/core/service.py:78)
298 DEBUG device                      Jun 01 13:43:53  add_service Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/device.py:95)
299 DEBUG device                      Jun 01 13:43:53  got device description from 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml' (coherence/upnp/core/device.py:358)
300 INFO  service_client              Jun 01 13:43:53  HDD-Kombinat urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager initialized (coherence/upnp/core/service.py:78)
301 DEBUG device                      Jun 01 13:43:53  add_service Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/device.py:95)
302 INFO  service_client              Jun 01 13:43:53  HDD-Kombinat urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory initialized (coherence/upnp/core/service.py:78)
303 DEBUG device                      Jun 01 13:43:53  add_service Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/device.py:95)
304 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <GET /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/scpd.xml HTTP/1.0> (coherence/base.py:46)
305 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, ConnectionManager, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/scpd.xml IPv4Address(TCP, '192.168.1.222', 52581) (coherence/upnp/devices/media_server.py:68)
306 INFO  mediaserver                 Jun 01 13:43:53  {'host': '192.168.1.222', 'user-agent': 'Twisted PageGetter'} (coherence/upnp/devices/media_server.py:70)
307 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <GET /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/scpd.xml HTTP/1.0> (coherence/base.py:46)
308 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, ContentDirectory, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/scpd.xml IPv4Address(TCP, '192.168.1.222', 52582) (coherence/upnp/devices/media_server.py:68)
309 INFO  mediaserver                 Jun 01 13:43:53  {'host': '192.168.1.222', 'user-agent': 'Twisted PageGetter'} (coherence/upnp/devices/media_server.py:70)
310 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <GET /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/scpd.xml HTTP/1.0> (coherence/base.py:46)
311 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, ConnectionManager, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/scpd.xml IPv4Address(TCP, '192.168.1.222', 52583) (coherence/upnp/devices/media_server.py:68)
312 INFO  mediaserver                 Jun 01 13:43:53  {'host': '192.168.1.222', 'user-agent': 'Twisted PageGetter'} (coherence/upnp/devices/media_server.py:70)
313 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <GET /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/scpd.xml HTTP/1.0> (coherence/base.py:46)
314 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, ContentDirectory, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/scpd.xml IPv4Address(TCP, '192.168.1.222', 52584) (coherence/upnp/devices/media_server.py:68)
315 INFO  mediaserver                 Jun 01 13:43:53  {'host': '192.168.1.222', 'user-agent': 'Twisted PageGetter'} (coherence/upnp/devices/media_server.py:70)
316 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.Service.detection_completed for Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:362)
317 DEBUG device                      Jun 01 13:43:53  device receiver called with 'Coherence.UPnP.Service.detection_completed' {'device': rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222', manifestation 'local', 'sender': rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222', manifestation 'local'} (coherence/upnp/core/device.py:61)
318 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.Service.detection_completed for Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:362)
319 DEBUG device                      Jun 01 13:43:53  device receiver called with 'Coherence.UPnP.Service.detection_completed' {'device': rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222', manifestation 'local', 'sender': rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222', manifestation 'local'} (coherence/upnp/core/device.py:61)
320 INFO  controlpoint                Jun 01 13:43:53  found device InternetRadio of type urn:schemas-upnp-org:device:MediaServer:2 - None (coherence/upnp/devices/control_point.py:119)
321 INFO  controlpoint                Jun 01 13:43:53  identified MediaServer u'InternetRadio' (coherence/upnp/devices/control_point.py:123)
322 DEBUG service_client              Jun 01 13:43:53  subscribe urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:231)
323 INFO  event_protocol              Jun 01 13:43:53  event.subscribe, action: 'subscribe' (coherence/upnp/core/event.py:247)
324 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection action: 'subscribe' None (coherence/upnp/core/event.py:309)
325 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection: '192.168.1.222' 30020 (coherence/upnp/core/event.py:313)
326 DEBUG service_client              Jun 01 13:43:53  subscribe urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:231)
327 INFO  event_protocol              Jun 01 13:43:53  event.subscribe, action: 'subscribe' (coherence/upnp/core/event.py:247)
328 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection action: 'subscribe' None (coherence/upnp/core/event.py:309)
329 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection: '192.168.1.222' 30020 (coherence/upnp/core/event.py:313)
330 INFO  ms_client                   Jun 01 13:43:53  MediaServer InternetRadio (coherence/upnp/devices/media_server_client.py:43)
331 INFO  ms_client                   Jun 01 13:43:53  ContentDirectory available (coherence/upnp/devices/media_server_client.py:45)
332 INFO  ms_client                   Jun 01 13:43:53  ConnectionManager available (coherence/upnp/devices/media_server_client.py:50)
333 DEBUG device                      Jun 01 13:43:53  device_detect {'device': rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222', manifestation 'local', 'sender': rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222', manifestation 'local'} (coherence/upnp/core/device.py:328)
334 DEBUG device                      Jun 01 13:43:53  root_detection_completed False (coherence/upnp/core/device.py:331)
335 DEBUG device                      Jun 01 13:43:53  detection_completed True (coherence/upnp/core/device.py:335)
336 DEBUG device                      Jun 01 13:43:53  self.devices [] (coherence/upnp/core/device.py:337)
337 INFO  device                      Jun 01 13:43:53  rootdevice u'InternetRadio' 'upnp:rootdevice' '192.168.1.222' initialized, manifestation 'local' (coherence/upnp/core/device.py:344)
338 INFO  controlpoint                Jun 01 13:43:53  found device InternetRadio of type urn:schemas-upnp-org:device:MediaServer:2 - <coherence.upnp.devices.media_server_client.MediaServerClient object at 0x8d961cc> (coherence/upnp/devices/control_point.py:119)
339 INFO  coherence                   Jun 01 13:43:53  adding device'uuid:51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca::upnp:rootdevice' (coherence/base.py:597)
340 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.Service.detection_completed for Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:362)
341 DEBUG device                      Jun 01 13:43:53  device receiver called with 'Coherence.UPnP.Service.detection_completed' {'device': rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222', manifestation 'local', 'sender': rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222', manifestation 'local'} (coherence/upnp/core/device.py:61)
342 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.Service.detection_completed for Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:362)
343 DEBUG device                      Jun 01 13:43:53  device receiver called with 'Coherence.UPnP.Service.detection_completed' {'device': rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222', manifestation 'local', 'sender': rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222', manifestation 'local'} (coherence/upnp/core/device.py:61)
344 INFO  controlpoint                Jun 01 13:43:53  found device HDD-Kombinat of type urn:schemas-upnp-org:device:MediaServer:2 - None (coherence/upnp/devices/control_point.py:119)
345 INFO  controlpoint                Jun 01 13:43:53  identified MediaServer u'HDD-Kombinat' (coherence/upnp/devices/control_point.py:123)
346 DEBUG service_client              Jun 01 13:43:53  subscribe urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:231)
347 INFO  event_protocol              Jun 01 13:43:53  event.subscribe, action: 'subscribe' (coherence/upnp/core/event.py:247)
348 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection action: 'subscribe' None (coherence/upnp/core/event.py:309)
349 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection: '192.168.1.222' 30020 (coherence/upnp/core/event.py:313)
350 DEBUG service_client              Jun 01 13:43:53  subscribe urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:231)
351 INFO  event_protocol              Jun 01 13:43:53  event.subscribe, action: 'subscribe' (coherence/upnp/core/event.py:247)
352 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection action: 'subscribe' None (coherence/upnp/core/event.py:309)
353 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.prepare_connection: '192.168.1.222' 30020 (coherence/upnp/core/event.py:313)
354 INFO  ms_client                   Jun 01 13:43:53  MediaServer HDD-Kombinat (coherence/upnp/devices/media_server_client.py:43)
355 INFO  ms_client                   Jun 01 13:43:53  ContentDirectory available (coherence/upnp/devices/media_server_client.py:45)
356 INFO  ms_client                   Jun 01 13:43:53  ConnectionManager available (coherence/upnp/devices/media_server_client.py:50)
357 DEBUG device                      Jun 01 13:43:53  device_detect {'device': rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222', manifestation 'local', 'sender': rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222', manifestation 'local'} (coherence/upnp/core/device.py:328)
358 DEBUG device                      Jun 01 13:43:53  root_detection_completed False (coherence/upnp/core/device.py:331)
359 DEBUG device                      Jun 01 13:43:53  detection_completed True (coherence/upnp/core/device.py:335)
360 DEBUG device                      Jun 01 13:43:53  self.devices [] (coherence/upnp/core/device.py:337)
361 INFO  device                      Jun 01 13:43:53  rootdevice u'HDD-Kombinat' 'upnp:rootdevice' '192.168.1.222' initialized, manifestation 'local' (coherence/upnp/core/device.py:344)
362 INFO  controlpoint                Jun 01 13:43:53  found device HDD-Kombinat of type urn:schemas-upnp-org:device:MediaServer:2 - <coherence.upnp.devices.media_server_client.MediaServerClient object at 0x8db1f2c> (coherence/upnp/devices/control_point.py:119)
363 INFO  coherence                   Jun 01 13:43:53  adding device'uuid:a8d9e91f-d924-427a-8bca-bf95ed2c11cc::upnp:rootdevice' (coherence/base.py:597)
364 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.send_request <coherence.upnp.core.event.EventProtocol object at 0x8d9e4cc>, action: 'subscribe' 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe' (coherence/upnp/core/event.py:259)
365 DEBUG event_protocol              Jun 01 13:43:53  event.subscribe.send_request 'SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe HTTP/1.1\r\nHOST: 192.168.1.222:30020\r\nTIMEOUT: Second-1800\r\nCALLBACK: <http://192.168.1.222:30020/events>\r\nNT: upnp:event\r\nDate: Mo, 01 Jun 2009 11:43:53 GMT\r\nContent-Length: 0\r\n\r\n' <coherence.upnp.core.event.EventProtocol object at 0x8d9e4cc> (coherence/upnp/core/event.py:290)
366 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.send_request <coherence.upnp.core.event.EventProtocol object at 0x8d9e0ec>, action: 'subscribe' 'http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe' (coherence/upnp/core/event.py:259)
367 DEBUG event_protocol              Jun 01 13:43:53  event.subscribe.send_request 'SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe HTTP/1.1\r\nHOST: 192.168.1.222:30020\r\nTIMEOUT: Second-1800\r\nCALLBACK: <http://192.168.1.222:30020/events>\r\nNT: upnp:event\r\nDate: Mo, 01 Jun 2009 11:43:53 GMT\r\nContent-Length: 0\r\n\r\n' <coherence.upnp.core.event.EventProtocol object at 0x8d9e0ec> (coherence/upnp/core/event.py:290)
368 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild ttp:, <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
369 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild , <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
370 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 192.168.1.222:30020, <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
371 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
372 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, SUBSCRIBE, ConnectionManager, http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/subscribe IPv4Address(TCP, '192.168.1.222', 52585) (coherence/upnp/devices/media_server.py:68)
373 INFO  mediaserver                 Jun 01 13:43:53  {'content-length': '0', 'callback': '<http://192.168.1.222:30020/events>', 'host': '192.168.1.222:30020', 'timeout': 'Second-1800', 'date': 'Mo, 01 Jun 2009 11:43:53 GMT', 'nt': 'upnp:event'} (coherence/upnp/devices/media_server.py:70)
374 INFO  event_subscription_server   Jun 01 13:43:53  EventSubscriptionServer ConnectionManager (InternetRadio) received subscribe request from IPv4Address(TCP, '192.168.1.222', 52585), code: 200 (coherence/upnp/core/event.py:114)
375 INFO  connection_manager_server   Jun 01 13:43:53  new_subscriber{'callback': 'http://192.168.1.222:30020/events', 'created': 1243856633.702085, 'timeout': 'Second-1800', 'seq': 0, 'sid': 'uuid:df7ad1b5-0ce8-4dfb-827d-7d9f4de87331'} [Variable: SinkProtocolInfo, <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec>, 0, required, string, [], , , , True, Variable: CurrentConnectionIDs, <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec>, 0, required, string, [], , , , True, Variable: SourceProtocolInfo, <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8d916ec>, 0, required, string, [], http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*, , http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*, True] (coherence/upnp/core/service.py:475)
376 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild ttp:, <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
377 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild , <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
378 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 192.168.1.222:30020, <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
379 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <SUBSCRIBE http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
380 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, SUBSCRIBE, ContentDirectory, http://192.168.1.222:30020/51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/subscribe IPv4Address(TCP, '192.168.1.222', 52586) (coherence/upnp/devices/media_server.py:68)
381 INFO  mediaserver                 Jun 01 13:43:53  {'content-length': '0', 'callback': '<http://192.168.1.222:30020/events>', 'host': '192.168.1.222:30020', 'timeout': 'Second-1800', 'date': 'Mo, 01 Jun 2009 11:43:53 GMT', 'nt': 'upnp:event'} (coherence/upnp/devices/media_server.py:70)
382 INFO  event_subscription_server   Jun 01 13:43:53  EventSubscriptionServer ContentDirectory (InternetRadio) received subscribe request from IPv4Address(TCP, '192.168.1.222', 52586), code: 200 (coherence/upnp/core/event.py:114)
383 INFO  content_directory_server    Jun 01 13:43:53  new_subscriber{'callback': 'http://192.168.1.222:30020/events', 'created': 1243856633.704761, 'timeout': 'Second-1800', 'seq': 0, 'sid': 'uuid:21acee85-1043-4808-aa97-4743d17e728a'} [Variable: ContainerUpdateIDs, <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c>, 0, optional, string, [], , , , True, Variable: SystemUpdateID, <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c>, 0, optional, ui4, [], 0, 0, 1, True, Variable: TransferIDs, <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8d96a0c>, 0, optional, string, [], , , , True] (coherence/upnp/core/service.py:475)
384 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.send_request <coherence.upnp.core.event.EventProtocol object at 0x8daf42c>, action: 'subscribe' 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe' (coherence/upnp/core/event.py:259)
385 DEBUG event_protocol              Jun 01 13:43:53  event.subscribe.send_request 'SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe HTTP/1.1\r\nHOST: 192.168.1.222:30020\r\nTIMEOUT: Second-1800\r\nCALLBACK: <http://192.168.1.222:30020/events>\r\nNT: upnp:event\r\nDate: Mo, 01 Jun 2009 11:43:53 GMT\r\nContent-Length: 0\r\n\r\n' <coherence.upnp.core.event.EventProtocol object at 0x8daf42c> (coherence/upnp/core/event.py:290)
386 INFO  event_protocol              Jun 01 13:43:53  event.subscribe.send_request <coherence.upnp.core.event.EventProtocol object at 0x8daf1ec>, action: 'subscribe' 'http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe' (coherence/upnp/core/event.py:259)
387 DEBUG event_protocol              Jun 01 13:43:53  event.subscribe.send_request 'SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe HTTP/1.1\r\nHOST: 192.168.1.222:30020\r\nTIMEOUT: Second-1800\r\nCALLBACK: <http://192.168.1.222:30020/events>\r\nNT: upnp:event\r\nDate: Mo, 01 Jun 2009 11:43:53 GMT\r\nContent-Length: 0\r\n\r\n' <coherence.upnp.core.event.EventProtocol object at 0x8daf1ec> (coherence/upnp/core/event.py:290)
388 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild ttp:, <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
389 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild , <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
390 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 192.168.1.222:30020, <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
391 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe HTTP/1.1> (coherence/base.py:46)
392 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, SUBSCRIBE, ConnectionManager, http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/subscribe IPv4Address(TCP, '192.168.1.222', 52587) (coherence/upnp/devices/media_server.py:68)
393 INFO  mediaserver                 Jun 01 13:43:53  {'content-length': '0', 'callback': '<http://192.168.1.222:30020/events>', 'host': '192.168.1.222:30020', 'timeout': 'Second-1800', 'date': 'Mo, 01 Jun 2009 11:43:53 GMT', 'nt': 'upnp:event'} (coherence/upnp/devices/media_server.py:70)
394 INFO  event_subscription_server   Jun 01 13:43:53  EventSubscriptionServer ConnectionManager (HDD-Kombinat) received subscribe request from IPv4Address(TCP, '192.168.1.222', 52587), code: 200 (coherence/upnp/core/event.py:114)
395 INFO  connection_manager_server   Jun 01 13:43:53  new_subscriber{'callback': 'http://192.168.1.222:30020/events', 'created': 1243856633.708497, 'timeout': 'Second-1800', 'seq': 0, 'sid': 'uuid:0fddfc19-4249-4bc8-80c9-456612065665'} [Variable: SinkProtocolInfo, <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c>, 0, required, string, [], , , , True, Variable: CurrentConnectionIDs, <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c>, 0, required, string, [], , , , True, Variable: SourceProtocolInfo, <coherence.upnp.services.servers.connection_manager_server.ConnectionManagerServer object at 0x8df784c>, 0, required, string, [], internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000, , internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000, True] (coherence/upnp/core/service.py:475)
396 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild ttp:, <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
397 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild , <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
398 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 192.168.1.222:30020, <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
399 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <SUBSCRIBE http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe HTTP/1.1> (coherence/base.py:46)
400 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, SUBSCRIBE, ContentDirectory, http://192.168.1.222:30020/a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/subscribe IPv4Address(TCP, '192.168.1.222', 52588) (coherence/upnp/devices/media_server.py:68)
401 INFO  mediaserver                 Jun 01 13:43:53  {'content-length': '0', 'callback': '<http://192.168.1.222:30020/events>', 'host': '192.168.1.222:30020', 'timeout': 'Second-1800', 'date': 'Mo, 01 Jun 2009 11:43:53 GMT', 'nt': 'upnp:event'} (coherence/upnp/devices/media_server.py:70)
402 INFO  event_subscription_server   Jun 01 13:43:53  EventSubscriptionServer ContentDirectory (HDD-Kombinat) received subscribe request from IPv4Address(TCP, '192.168.1.222', 52588), code: 200 (coherence/upnp/core/event.py:114)
403 INFO  content_directory_server    Jun 01 13:43:53  new_subscriber{'callback': 'http://192.168.1.222:30020/events', 'created': 1243856633.710536, 'timeout': 'Second-1800', 'seq': 0, 'sid': 'uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3'} [Variable: ContainerUpdateIDs, <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c>, 0, optional, string, [], , , , True, Variable: SystemUpdateID, <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c>, 0, optional, ui4, [], 0, , 0, True, Variable: TransferIDs, <coherence.upnp.services.servers.content_directory_server.ContentDirectoryServer object at 0x8da526c>, 0, optional, string, [], , , , True] (coherence/upnp/core/service.py:475)
404 INFO  notification_protocol       Jun 01 13:43:53  send_notification.send_request to 'uuid:df7ad1b5-0ce8-4dfb-827d-7d9f4de87331' 'http://192.168.1.222:30020/events' (coherence/upnp/core/event.py:395)
405 DEBUG notification_protocol       Jun 01 13:43:53  request: 'NOTIFY /events HTTP/1.1\r\nHOST:  192.168.1.222:30020\r\nSEQ:  0\r\nCONTENT-TYPE:  text/xml;charset="utf-8"\r\nSID:  uuid:df7ad1b5-0ce8-4dfb-827d-7d9f4de87331\r\nNTS:  upnp:propchange\r\nNT:  upnp:event\r\nContent-Length: 438\r\n\r\n<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SinkProtocolInfo /></e:property><e:property><CurrentConnectionIDs /></e:property><e:property><SourceProtocolInfo>http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*</SourceProtocolInfo></e:property></e:propertyset>' (coherence/upnp/core/event.py:396)
406 INFO  notification_protocol       Jun 01 13:43:53  send_notification.send_request to 'uuid:21acee85-1043-4808-aa97-4743d17e728a' 'http://192.168.1.222:30020/events' (coherence/upnp/core/event.py:395)
407 DEBUG notification_protocol       Jun 01 13:43:53  request: 'NOTIFY /events HTTP/1.1\r\nHOST:  192.168.1.222:30020\r\nSEQ:  0\r\nCONTENT-TYPE:  text/xml;charset="utf-8"\r\nSID:  uuid:21acee85-1043-4808-aa97-4743d17e728a\r\nNTS:  upnp:propchange\r\nNT:  upnp:event\r\nContent-Length: 218\r\n\r\n<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ContainerUpdateIDs /></e:property><e:property><SystemUpdateID>1</SystemUpdateID></e:property><e:property><TransferIDs /></e:property></e:propertyset>' (coherence/upnp/core/event.py:396)
408 INFO  event_protocol              Jun 01 13:43:53  response received from the Service Events HTTP server  (coherence/upnp/core/event.py:209)
409 DEBUG event_protocol              Jun 01 13:43:53  ['HTTP/1.1', '200', 'OK'] {'content-length': '0', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'timeout': 'Second-1800', 'sid': 'uuid:df7ad1b5-0ce8-4dfb-827d-7d9f4de87331', 'date': 'Mon, 01 Jun 2009 11:43:53 GMT', 'content-type': 'text/html'} (coherence/upnp/core/event.py:212)
410 INFO  service_client              Jun 01 13:43:53  set subscription id for InternetRadio/urn:schemas-upnp-org:service:ConnectionManager:2 to uuid:df7ad1b5-0ce8-4dfb-827d-7d9f4de87331 (coherence/upnp/core/service.py:194)
411 DEBUG service_client              Jun 01 13:43:53  add subscription for urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:198)
412 DEBUG event_protocol              Jun 01 13:43:53  'uuid:df7ad1b5-0ce8-4dfb-827d-7d9f4de87331' 'Second-1800' (coherence/upnp/core/event.py:219)
413 INFO  service_client              Jun 01 13:43:53  set timout for InternetRadio/urn:schemas-upnp-org:service:ConnectionManager:2 to 1800 (coherence/upnp/core/service.py:174)
414 INFO  service_client              Jun 01 13:43:53  starting renew subscription call for InternetRadio/urn:schemas-upnp-org:service:ConnectionManager:2 to 1770 (coherence/upnp/core/service.py:182)
415 INFO  event_protocol              Jun 01 13:43:53  response received from the Service Events HTTP server  (coherence/upnp/core/event.py:209)
416 DEBUG event_protocol              Jun 01 13:43:53  ['HTTP/1.1', '200', 'OK'] {'content-length': '0', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'timeout': 'Second-1800', 'sid': 'uuid:21acee85-1043-4808-aa97-4743d17e728a', 'date': 'Mon, 01 Jun 2009 11:43:53 GMT', 'content-type': 'text/html'} (coherence/upnp/core/event.py:212)
417 INFO  service_client              Jun 01 13:43:53  set subscription id for InternetRadio/urn:schemas-upnp-org:service:ContentDirectory:2 to uuid:21acee85-1043-4808-aa97-4743d17e728a (coherence/upnp/core/service.py:194)
418 DEBUG service_client              Jun 01 13:43:53  add subscription for urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:198)
419 DEBUG event_protocol              Jun 01 13:43:53  'uuid:21acee85-1043-4808-aa97-4743d17e728a' 'Second-1800' (coherence/upnp/core/event.py:219)
420 INFO  service_client              Jun 01 13:43:53  set timout for InternetRadio/urn:schemas-upnp-org:service:ContentDirectory:2 to 1800 (coherence/upnp/core/service.py:174)
421 INFO  service_client              Jun 01 13:43:53  starting renew subscription call for InternetRadio/urn:schemas-upnp-org:service:ContentDirectory:2 to 1770 (coherence/upnp/core/service.py:182)
422 INFO  notification_protocol       Jun 01 13:43:53  send_notification.send_request to 'uuid:0fddfc19-4249-4bc8-80c9-456612065665' 'http://192.168.1.222:30020/events' (coherence/upnp/core/event.py:395)
423 DEBUG notification_protocol       Jun 01 13:43:53  request: 'NOTIFY /events HTTP/1.1\r\nHOST:  192.168.1.222:30020\r\nSEQ:  0\r\nCONTENT-TYPE:  text/xml;charset="utf-8"\r\nSID:  uuid:0fddfc19-4249-4bc8-80c9-456612065665\r\nNTS:  upnp:propchange\r\nNT:  upnp:event\r\nContent-Length: 2257\r\n\r\n<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SinkProtocolInfo /></e:property><e:property><CurrentConnectionIDs /></e:property><e:property><SourceProtocolInfo>internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000</SourceProtocolInfo></e:property></e:propertyset>' (coherence/upnp/core/event.py:396)
424 INFO  notification_protocol       Jun 01 13:43:53  send_notification.send_request to 'uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3' 'http://192.168.1.222:30020/events' (coherence/upnp/core/event.py:395)
425 DEBUG notification_protocol       Jun 01 13:43:53  request: 'NOTIFY /events HTTP/1.1\r\nHOST:  192.168.1.222:30020\r\nSEQ:  0\r\nCONTENT-TYPE:  text/xml;charset="utf-8"\r\nSID:  uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3\r\nNTS:  upnp:propchange\r\nNT:  upnp:event\r\nContent-Length: 218\r\n\r\n<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ContainerUpdateIDs /></e:property><e:property><SystemUpdateID>0</SystemUpdateID></e:property><e:property><TransferIDs /></e:property></e:propertyset>' (coherence/upnp/core/event.py:396)
426 DEBUG notification_protocol       Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> (coherence/upnp/core/event.py:361)
427 DEBUG notification_protocol       Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> (coherence/upnp/core/event.py:361)
428 INFO  event_protocol              Jun 01 13:43:53  response received from the Service Events HTTP server  (coherence/upnp/core/event.py:209)
429 DEBUG event_protocol              Jun 01 13:43:53  ['HTTP/1.1', '200', 'OK'] {'content-length': '0', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'timeout': 'Second-1800', 'sid': 'uuid:0fddfc19-4249-4bc8-80c9-456612065665', 'date': 'Mon, 01 Jun 2009 11:43:53 GMT', 'content-type': 'text/html'} (coherence/upnp/core/event.py:212)
430 INFO  service_client              Jun 01 13:43:53  set subscription id for HDD-Kombinat/urn:schemas-upnp-org:service:ConnectionManager:2 to uuid:0fddfc19-4249-4bc8-80c9-456612065665 (coherence/upnp/core/service.py:194)
431 DEBUG service_client              Jun 01 13:43:53  add subscription for urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:198)
432 DEBUG event_protocol              Jun 01 13:43:53  'uuid:0fddfc19-4249-4bc8-80c9-456612065665' 'Second-1800' (coherence/upnp/core/event.py:219)
433 INFO  service_client              Jun 01 13:43:53  set timout for HDD-Kombinat/urn:schemas-upnp-org:service:ConnectionManager:2 to 1800 (coherence/upnp/core/service.py:174)
434 INFO  service_client              Jun 01 13:43:53  starting renew subscription call for HDD-Kombinat/urn:schemas-upnp-org:service:ConnectionManager:2 to 1770 (coherence/upnp/core/service.py:182)
435 INFO  event_protocol              Jun 01 13:43:53  response received from the Service Events HTTP server  (coherence/upnp/core/event.py:209)
436 DEBUG event_protocol              Jun 01 13:43:53  ['HTTP/1.1', '200', 'OK'] {'content-length': '0', 'server': 'Linux,2.6.28.4,UPnP/1.0,Coherence UPnP framework,0.6.4', 'timeout': 'Second-1800', 'sid': 'uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3', 'date': 'Mon, 01 Jun 2009 11:43:53 GMT', 'content-type': 'text/html'} (coherence/upnp/core/event.py:212)
437 INFO  service_client              Jun 01 13:43:53  set subscription id for HDD-Kombinat/urn:schemas-upnp-org:service:ContentDirectory:2 to uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3 (coherence/upnp/core/service.py:194)
438 DEBUG service_client              Jun 01 13:43:53  add subscription for urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:198)
439 DEBUG event_protocol              Jun 01 13:43:53  'uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3' 'Second-1800' (coherence/upnp/core/event.py:219)
440 INFO  service_client              Jun 01 13:43:53  set timout for HDD-Kombinat/urn:schemas-upnp-org:service:ContentDirectory:2 to 1800 (coherence/upnp/core/service.py:174)
441 INFO  service_client              Jun 01 13:43:53  starting renew subscription call for HDD-Kombinat/urn:schemas-upnp-org:service:ContentDirectory:2 to 1770 (coherence/upnp/core/service.py:182)
442 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild events, <NOTIFY /events HTTP/1.1> (coherence/base.py:46)
443 INFO  event_server                Jun 01 13:43:53  EventServer received notify from IPv4Address(TCP, '192.168.1.222', 52589), code: 200 (coherence/upnp/core/event.py:39)
444 DEBUG event_server                Jun 01 13:43:53  data:'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SinkProtocolInfo /></e:property><e:property><CurrentConnectionIDs /></e:property><e:property><SourceProtocolInfo>http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*</SourceProtocolInfo></e:property></e:propertyset>' (coherence/upnp/core/event.py:50)
445 INFO  controlpoint                Jun 01 13:43:53  propagate: {'SinkProtocolInfo': '', 'CurrentConnectionIDs': '', 'SourceProtocolInfo': 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*'} (coherence/upnp/devices/control_point.py:149)
446 INFO  service_client              Jun 01 13:43:53  process event Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager {'SinkProtocolInfo': '', 'CurrentConnectionIDs': '', 'SourceProtocolInfo': 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*'} (coherence/upnp/core/service.py:267)
447 INFO  variable                    Jun 01 13:43:53  variable check for update'SinkProtocolInfo' '' Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/variable.py:80)
448 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SinkProtocolInfo' '' (coherence/upnp/core/variable.py:170)
449 INFO  variable                    Jun 01 13:43:53  variable check for update'CurrentConnectionIDs' '' Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/variable.py:80)
450 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'CurrentConnectionIDs' '' (coherence/upnp/core/variable.py:170)
451 INFO  variable                    Jun 01 13:43:53  variable check for update'SourceProtocolInfo' 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*' Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/variable.py:80)
452 INFO  variable                    Jun 01 13:43:53  Variable SourceProtocolInfo sends notify about new value >'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*'< (coherence/upnp/core/variable.py:191)
453 INFO  variable                    Jun 01 13:43:53  variable updated'SourceProtocolInfo' 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*' (coherence/upnp/core/variable.py:182)
454 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.DeviceClient.Service.notified for Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:313)
455 INFO  ms_client                   Jun 01 13:43:53  notified about Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/devices/media_server_client.py:79)
456 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild events, <NOTIFY /events HTTP/1.1> (coherence/base.py:46)
457 INFO  event_server                Jun 01 13:43:53  EventServer received notify from IPv4Address(TCP, '192.168.1.222', 52590), code: 200 (coherence/upnp/core/event.py:39)
458 DEBUG event_server                Jun 01 13:43:53  data:'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ContainerUpdateIDs /></e:property><e:property><SystemUpdateID>1</SystemUpdateID></e:property><e:property><TransferIDs /></e:property></e:propertyset>' (coherence/upnp/core/event.py:50)
459 INFO  controlpoint                Jun 01 13:43:53  propagate: {'SystemUpdateID': '1', 'ContainerUpdateIDs': '', 'TransferIDs': ''} (coherence/upnp/devices/control_point.py:149)
460 INFO  service_client              Jun 01 13:43:53  process event Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory {'SystemUpdateID': '1', 'ContainerUpdateIDs': '', 'TransferIDs': ''} (coherence/upnp/core/service.py:267)
461 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' '1' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
462 INFO  variable                    Jun 01 13:43:53  Variable SystemUpdateID sends notify about new value >1< (coherence/upnp/core/variable.py:191)
463 INFO  variable                    Jun 01 13:43:53  variable updated'SystemUpdateID' 1 (coherence/upnp/core/variable.py:182)
464 INFO  variable                    Jun 01 13:43:53  variable check for update'ContainerUpdateIDs' '' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
465 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'ContainerUpdateIDs' '' (coherence/upnp/core/variable.py:170)
466 INFO  variable                    Jun 01 13:43:53  variable check for update'TransferIDs' '' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
467 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'TransferIDs' '' (coherence/upnp/core/variable.py:170)
468 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.DeviceClient.Service.notified for Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:313)
469 INFO  ms_client                   Jun 01 13:43:53  notified about Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/devices/media_server_client.py:79)
470 INFO  ms_client                   Jun 01 13:43:53  detection_completed for <coherence.upnp.devices.media_server_client.MediaServerClient object at 0x8d961cc> (coherence/upnp/devices/media_server_client.py:105)
471 DEBUG event_protocol              Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> from the Service Events HTTP server (coherence/upnp/core/event.py:235)
472 INFO  controlpoint                Jun 01 13:43:53  sending signal Coherence.UPnP.ControlPoint.MediaServer.detected  (coherence/upnp/devices/control_point.py:139)
473 DEBUG event_protocol              Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> from the Service Events HTTP server (coherence/upnp/core/event.py:235)
474 DEBUG notification_protocol       Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> (coherence/upnp/core/event.py:361)
475 DEBUG notification_protocol       Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> (coherence/upnp/core/event.py:361)
476 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild events, <NOTIFY /events HTTP/1.1> (coherence/base.py:46)
477 INFO  event_server                Jun 01 13:43:53  EventServer received notify from IPv4Address(TCP, '192.168.1.222', 52591), code: 200 (coherence/upnp/core/event.py:39)
478 DEBUG event_server                Jun 01 13:43:53  data:'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SinkProtocolInfo /></e:property><e:property><CurrentConnectionIDs /></e:property><e:property><SourceProtocolInfo>internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000</SourceProtocolInfo></e:property></e:propertyset>' (coherence/upnp/core/event.py:50)
479 INFO  controlpoint                Jun 01 13:43:53  propagate: {'SinkProtocolInfo': '', 'CurrentConnectionIDs': '', 'SourceProtocolInfo': 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000'} (coherence/upnp/devices/control_point.py:149)
480 INFO  service_client              Jun 01 13:43:53  process event Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager {'SinkProtocolInfo': '', 'CurrentConnectionIDs': '', 'SourceProtocolInfo': 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000'} (coherence/upnp/core/service.py:267)
481 INFO  variable                    Jun 01 13:43:53  variable check for update'SinkProtocolInfo' '' Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/variable.py:80)
482 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'SinkProtocolInfo' '' (coherence/upnp/core/variable.py:170)
483 INFO  variable                    Jun 01 13:43:53  variable check for update'CurrentConnectionIDs' '' Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/variable.py:80)
484 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'CurrentConnectionIDs' '' (coherence/upnp/core/variable.py:170)
485 INFO  variable                    Jun 01 13:43:53  variable check for update'SourceProtocolInfo' 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000' Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/variable.py:80)
486 INFO  variable                    Jun 01 13:43:53  Variable SourceProtocolInfo sends notify about new value >'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000'< (coherence/upnp/core/variable.py:191)
487 INFO  variable                    Jun 01 13:43:53  variable updated'SourceProtocolInfo' 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000' (coherence/upnp/core/variable.py:182)
488 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.DeviceClient.Service.notified for Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/core/service.py:313)
489 INFO  ms_client                   Jun 01 13:43:53  notified about Service urn:schemas-upnp-org:service:ConnectionManager:2 urn:upnp-org:serviceId:ConnectionManager (coherence/upnp/devices/media_server_client.py:79)
490 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild events, <NOTIFY /events HTTP/1.1> (coherence/base.py:46)
491 INFO  event_server                Jun 01 13:43:53  EventServer received notify from IPv4Address(TCP, '192.168.1.222', 52592), code: 200 (coherence/upnp/core/event.py:39)
492 DEBUG event_server                Jun 01 13:43:53  data:'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ContainerUpdateIDs /></e:property><e:property><SystemUpdateID>0</SystemUpdateID></e:property><e:property><TransferIDs /></e:property></e:propertyset>' (coherence/upnp/core/event.py:50)
493 INFO  controlpoint                Jun 01 13:43:53  propagate: {'SystemUpdateID': '0', 'ContainerUpdateIDs': '', 'TransferIDs': ''} (coherence/upnp/devices/control_point.py:149)
494 INFO  service_client              Jun 01 13:43:53  process event Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory {'SystemUpdateID': '0', 'ContainerUpdateIDs': '', 'TransferIDs': ''} (coherence/upnp/core/service.py:267)
495 INFO  variable                    Jun 01 13:43:53  variable check for update'SystemUpdateID' '0' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
496 INFO  variable                    Jun 01 13:43:53  Variable SystemUpdateID sends notify about new value >0< (coherence/upnp/core/variable.py:191)
497 INFO  variable                    Jun 01 13:43:53  variable updated'SystemUpdateID' 0 (coherence/upnp/core/variable.py:182)
498 INFO  variable                    Jun 01 13:43:53  variable check for update'ContainerUpdateIDs' '' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
499 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'ContainerUpdateIDs' '' (coherence/upnp/core/variable.py:170)
500 INFO  variable                    Jun 01 13:43:53  variable check for update'TransferIDs' '' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
501 INFO  variable                    Jun 01 13:43:53  variable NOT updated, no value change'TransferIDs' '' (coherence/upnp/core/variable.py:170)
502 INFO  service_client              Jun 01 13:43:53  send signal Coherence.UPnP.DeviceClient.Service.notified for Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/service.py:313)
503 INFO  ms_client                   Jun 01 13:43:53  notified about Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/devices/media_server_client.py:79)
504 INFO  ms_client                   Jun 01 13:43:53  detection_completed for <coherence.upnp.devices.media_server_client.MediaServerClient object at 0x8db1f2c> (coherence/upnp/devices/media_server_client.py:105)
505 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <GET /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/scpd.xml HTTP/1.1> (coherence/base.py:46)
506 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, ConnectionManager, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/scpd.xml IPv4Address(TCP, '192.168.1.66', 61511) (coherence/upnp/devices/media_server.py:68)
507 INFO  mediaserver                 Jun 01 13:43:53  {'date': 'Mon, 01 Jun 2009 11:41:53 GMT', 'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'host': '192.168.1.222:30020', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0'} (coherence/upnp/devices/media_server.py:70)
508 INFO  controlpoint                Jun 01 13:43:53  sending signal Coherence.UPnP.ControlPoint.MediaServer.detected  (coherence/upnp/devices/control_point.py:139)
509 DEBUG event_protocol              Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> from the Service Events HTTP server (coherence/upnp/core/event.py:235)
510 DEBUG event_protocol              Jun 01 13:43:53  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> from the Service Events HTTP server (coherence/upnp/core/event.py:235)
511 DEBUG coherence                   Jun 01 13:43:53  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <GET /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/scpd.xml HTTP/1.1> (coherence/base.py:46)
512 INFO  mediaserver                 Jun 01 13:43:53  MediaServer getChildWithDefault, GET, ContentDirectory, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/scpd.xml IPv4Address(TCP, '192.168.1.66', 61512) (coherence/upnp/devices/media_server.py:68)
513 INFO  mediaserver                 Jun 01 13:43:53  {'date': 'Mon, 01 Jun 2009 11:41:53 GMT', 'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'host': '192.168.1.222:30020', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0'} (coherence/upnp/devices/media_server.py:70)
514 WARN  iradio_store                Jun 01 13:43:53  connection to ShoutCast service failed! <twisted.python.failure.Failure <class 'twisted.web.error.Error'>> (coherence/backends/iradio_storage.py:308)
515 DEBUG iradio_store                Jun 01 13:43:53  'Traceback (most recent call last):\nFailure: twisted.web.error.Error: 503 Service Temporarily Unavailable\n' (coherence/backends/iradio_storage.py:309)
516 INFO  notification_protocol       Jun 01 13:43:54  send_notification.send_request to 'uuid:21acee85-1043-4808-aa97-4743d17e728a' 'http://192.168.1.222:30020/events' (coherence/upnp/core/event.py:395)
517 DEBUG notification_protocol       Jun 01 13:43:54  request: 'NOTIFY /events HTTP/1.1\r\nHOST:  192.168.1.222:30020\r\nSEQ:  1\r\nCONTENT-TYPE:  text/xml;charset="utf-8"\r\nSID:  uuid:21acee85-1043-4808-aa97-4743d17e728a\r\nNTS:  upnp:propchange\r\nNT:  upnp:event\r\nContent-Length: 131\r\n\r\n<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SystemUpdateID>1</SystemUpdateID></e:property></e:propertyset>' (coherence/upnp/core/event.py:396)
518 DEBUG notification_protocol       Jun 01 13:43:54  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> (coherence/upnp/core/event.py:361)
519 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild events, <NOTIFY /events HTTP/1.1> (coherence/base.py:46)
520 INFO  event_server                Jun 01 13:43:54  EventServer received notify from IPv4Address(TCP, '192.168.1.222', 52593), code: 200 (coherence/upnp/core/event.py:39)
521 DEBUG event_server                Jun 01 13:43:54  data:'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SystemUpdateID>1</SystemUpdateID></e:property></e:propertyset>' (coherence/upnp/core/event.py:50)
522 INFO  controlpoint                Jun 01 13:43:54  propagate: {'SystemUpdateID': '1'} (coherence/upnp/devices/control_point.py:149)
523 INFO  service_client              Jun 01 13:43:54  process event Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory {'SystemUpdateID': '1'} (coherence/upnp/core/service.py:267)
524 INFO  variable                    Jun 01 13:43:54  variable check for update'SystemUpdateID' '1' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
525 INFO  variable                    Jun 01 13:43:54  variable NOT updated, no value change'SystemUpdateID' 1 (coherence/upnp/core/variable.py:170)
526 INFO  notification_protocol       Jun 01 13:43:54  send_notification.send_request to 'uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3' 'http://192.168.1.222:30020/events' (coherence/upnp/core/event.py:395)
527 DEBUG notification_protocol       Jun 01 13:43:54  request: 'NOTIFY /events HTTP/1.1\r\nHOST:  192.168.1.222:30020\r\nSEQ:  1\r\nCONTENT-TYPE:  text/xml;charset="utf-8"\r\nSID:  uuid:fe579192-9c8a-41b1-8af5-cb790114d4c3\r\nNTS:  upnp:propchange\r\nNT:  upnp:event\r\nContent-Length: 131\r\n\r\n<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SystemUpdateID>0</SystemUpdateID></e:property></e:propertyset>' (coherence/upnp/core/event.py:396)
528 DEBUG notification_protocol       Jun 01 13:43:54  connection closed <twisted.python.failure.Failure <class 'twisted.internet.error.ConnectionDone'>> (coherence/upnp/core/event.py:361)
529 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild events, <NOTIFY /events HTTP/1.1> (coherence/base.py:46)
530 INFO  event_server                Jun 01 13:43:54  EventServer received notify from IPv4Address(TCP, '192.168.1.222', 52594), code: 200 (coherence/upnp/core/event.py:39)
531 DEBUG event_server                Jun 01 13:43:54  data:'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><SystemUpdateID>0</SystemUpdateID></e:property></e:propertyset>' (coherence/upnp/core/event.py:50)
532 INFO  controlpoint                Jun 01 13:43:54  propagate: {'SystemUpdateID': '0'} (coherence/upnp/devices/control_point.py:149)
533 INFO  service_client              Jun 01 13:43:54  process event Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory {'SystemUpdateID': '0'} (coherence/upnp/core/service.py:267)
534 INFO  variable                    Jun 01 13:43:54  variable check for update'SystemUpdateID' '0' Service urn:schemas-upnp-org:service:ContentDirectory:2 urn:upnp-org:serviceId:ContentDirectory (coherence/upnp/core/variable.py:80)
535 INFO  variable                    Jun 01 13:43:54  variable NOT updated, no value change'SystemUpdateID' 0 (coherence/upnp/core/variable.py:170)
536 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <POST /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/control HTTP/1.1> (coherence/base.py:46)
537 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, POST, ContentDirectory, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/control IPv4Address(TCP, '192.168.1.66', 61513) (coherence/upnp/devices/media_server.py:68)
538 INFO  mediaserver                 Jun 01 13:43:54  {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '280', 'soapaction': '"urn:schemas-upnp-org:service:ContentDirectory:2#GetSortCapabilities"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/devices/media_server.py:70)
539 INFO  soap                        Jun 01 13:43:54  soap_request:{'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '280', 'soapaction': '"urn:schemas-upnp-org:service:ContentDirectory:2#GetSortCapabilities"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:95)
540 DEBUG soap                        Jun 01 13:43:54  headers: {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '280', 'soapaction': '"urn:schemas-upnp-org:service:ContentDirectory:2#GetSortCapabilities"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:132)
541 INFO  soap                        Jun 01 13:43:54  call'GetSortCapabilities' {'soap_methodName': 'GetSortCapabilities'} (coherence/upnp/core/soap_service.py:156)
542 INFO  soap                        Jun 01 13:43:54  soap__genericAction: GetSortCapabilities [required], (1 args) 'coherence.upnp.core.service' {'soap_methodName': 'GetSortCapabilities'} (coherence/upnp/core/service.py:908)
543 DEBUG soap                        Jun 01 13:43:54  get_action_results{} (coherence/upnp/core/service.py:864)
544 INFO  soap                        Jun 01 13:43:54  action_results unsorted'GetSortCapabilities' {'SortCaps': ''} (coherence/upnp/core/service.py:884)
545 INFO  soap                        Jun 01 13:43:54  action_results sorted'GetSortCapabilities' {'SortCaps': ''} (coherence/upnp/core/service.py:890)
546 DEBUG soap                        Jun 01 13:43:54  _gotResult{'SortCaps': ''} <POST /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ContentDirectory/control HTTP/1.1> 'GetSortCapabilities' 'urn:schemas-upnp-org:service:ContentDirectory:2' (coherence/upnp/core/soap_service.py:61)
547 DEBUG soap                        Jun 01 13:43:54  _sendResponse200 '<?xml version="1.0" encoding="utf-8"?><s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><ns0:GetSortCapabilitiesResponse xmlns:ns0="urn:schemas-upnp-org:service:ContentDirectory:2"><SortCaps /></ns0:GetSortCapabilitiesResponse></s:Body></s:Envelope>' (coherence/upnp/core/soap_service.py:39)
548 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild 51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca, <POST /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/control HTTP/1.1> (coherence/base.py:46)
549 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, POST, ConnectionManager, /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/control IPv4Address(TCP, '192.168.1.66', 61514) (coherence/upnp/devices/media_server.py:68)
550 INFO  mediaserver                 Jun 01 13:43:54  {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '273', 'soapaction': '"urn:schemas-upnp-org:service:ConnectionManager:2#GetProtocolInfo"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/devices/media_server.py:70)
551 INFO  soap                        Jun 01 13:43:54  soap_request:{'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '273', 'soapaction': '"urn:schemas-upnp-org:service:ConnectionManager:2#GetProtocolInfo"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:95)
552 DEBUG soap                        Jun 01 13:43:54  headers: {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '273', 'soapaction': '"urn:schemas-upnp-org:service:ConnectionManager:2#GetProtocolInfo"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:132)
553 INFO  soap                        Jun 01 13:43:54  call'GetProtocolInfo' {'soap_methodName': 'GetProtocolInfo'} (coherence/upnp/core/soap_service.py:156)
554 INFO  soap                        Jun 01 13:43:54  soap__genericAction: GetProtocolInfo [required], (2 args) 'coherence.upnp.core.service' {'soap_methodName': 'GetProtocolInfo'} (coherence/upnp/core/service.py:908)
555 DEBUG soap                        Jun 01 13:43:54  get_action_results{} (coherence/upnp/core/service.py:864)
556 INFO  soap                        Jun 01 13:43:54  action_results unsorted'GetProtocolInfo' {'Source': 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*', 'Sink': ''} (coherence/upnp/core/service.py:884)
557 INFO  soap                        Jun 01 13:43:54  action_results sorted'GetProtocolInfo' {'Source': 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*', 'Sink': ''} (coherence/upnp/core/service.py:890)
558 DEBUG soap                        Jun 01 13:43:54  _gotResult{'Source': 'http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*', 'Sink': ''} <POST /51c9b292-8d7f-42d7-b2d4-ad25d3aac8ca/ConnectionManager/control HTTP/1.1> 'GetProtocolInfo' 'urn:schemas-upnp-org:service:ConnectionManager:2' (coherence/upnp/core/soap_service.py:61)
559 DEBUG soap                        Jun 01 13:43:54  _sendResponse200 '<?xml version="1.0" encoding="utf-8"?><s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><ns0:GetProtocolInfoResponse xmlns:ns0="urn:schemas-upnp-org:service:ConnectionManager:2"><Source>http-get:*:audio/mpeg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/x-scpls:*</Source><Sink /></ns0:GetProtocolInfoResponse></s:Body></s:Envelope>' (coherence/upnp/core/soap_service.py:39)
560 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <GET /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml HTTP/1.1> (coherence/base.py:46)
561 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, GET, description-2.xml, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/description-2.xml IPv4Address(TCP, '192.168.1.66', 61515) (coherence/upnp/devices/media_server.py:68)
562 INFO  mediaserver                 Jun 01 13:43:54  {'date': 'Mon, 01 Jun 2009 11:41:53 GMT', 'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'host': '192.168.1.222:30020', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0'} (coherence/upnp/devices/media_server.py:70)
563 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <GET /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/scpd.xml HTTP/1.1> (coherence/base.py:46)
564 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, GET, ConnectionManager, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/scpd.xml IPv4Address(TCP, '192.168.1.66', 61516) (coherence/upnp/devices/media_server.py:68)
565 INFO  mediaserver                 Jun 01 13:43:54  {'date': 'Mon, 01 Jun 2009 11:41:54 GMT', 'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'host': '192.168.1.222:30020', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0'} (coherence/upnp/devices/media_server.py:70)
566 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <GET /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/scpd.xml HTTP/1.1> (coherence/base.py:46)
567 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, GET, ContentDirectory, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/scpd.xml IPv4Address(TCP, '192.168.1.66', 61517) (coherence/upnp/devices/media_server.py:68)
568 INFO  mediaserver                 Jun 01 13:43:54  {'date': 'Mon, 01 Jun 2009 11:41:54 GMT', 'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'host': '192.168.1.222:30020', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0'} (coherence/upnp/devices/media_server.py:70)
569 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <POST /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/control HTTP/1.1> (coherence/base.py:46)
570 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, POST, ContentDirectory, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/control IPv4Address(TCP, '192.168.1.66', 61518) (coherence/upnp/devices/media_server.py:68)
571 INFO  mediaserver                 Jun 01 13:43:54  {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '280', 'soapaction': '"urn:schemas-upnp-org:service:ContentDirectory:2#GetSortCapabilities"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/devices/media_server.py:70)
572 INFO  soap                        Jun 01 13:43:54  soap_request:{'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '280', 'soapaction': '"urn:schemas-upnp-org:service:ContentDirectory:2#GetSortCapabilities"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:95)
573 DEBUG soap                        Jun 01 13:43:54  headers: {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '280', 'soapaction': '"urn:schemas-upnp-org:service:ContentDirectory:2#GetSortCapabilities"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:132)
574 INFO  soap                        Jun 01 13:43:54  call'GetSortCapabilities' {'soap_methodName': 'GetSortCapabilities'} (coherence/upnp/core/soap_service.py:156)
575 INFO  soap                        Jun 01 13:43:54  soap__genericAction: GetSortCapabilities [required], (1 args) 'coherence.upnp.core.service' {'soap_methodName': 'GetSortCapabilities'} (coherence/upnp/core/service.py:908)
576 DEBUG soap                        Jun 01 13:43:54  get_action_results{} (coherence/upnp/core/service.py:864)
577 INFO  soap                        Jun 01 13:43:54  action_results unsorted'GetSortCapabilities' {'SortCaps': ''} (coherence/upnp/core/service.py:884)
578 INFO  soap                        Jun 01 13:43:54  action_results sorted'GetSortCapabilities' {'SortCaps': ''} (coherence/upnp/core/service.py:890)
579 DEBUG soap                        Jun 01 13:43:54  _gotResult{'SortCaps': ''} <POST /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ContentDirectory/control HTTP/1.1> 'GetSortCapabilities' 'urn:schemas-upnp-org:service:ContentDirectory:2' (coherence/upnp/core/soap_service.py:61)
580 DEBUG soap                        Jun 01 13:43:54  _sendResponse200 '<?xml version="1.0" encoding="utf-8"?><s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><ns0:GetSortCapabilitiesResponse xmlns:ns0="urn:schemas-upnp-org:service:ContentDirectory:2"><SortCaps /></ns0:GetSortCapabilitiesResponse></s:Body></s:Envelope>' (coherence/upnp/core/soap_service.py:39)
581 DEBUG coherence                   Jun 01 13:43:54  SimpleRoot getChild a8d9e91f-d924-427a-8bca-bf95ed2c11cc, <POST /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/control HTTP/1.1> (coherence/base.py:46)
582 INFO  mediaserver                 Jun 01 13:43:54  MediaServer getChildWithDefault, POST, ConnectionManager, /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/control IPv4Address(TCP, '192.168.1.66', 61519) (coherence/upnp/devices/media_server.py:68)
583 INFO  mediaserver                 Jun 01 13:43:54  {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '273', 'soapaction': '"urn:schemas-upnp-org:service:ConnectionManager:2#GetProtocolInfo"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/devices/media_server.py:70)
584 INFO  soap                        Jun 01 13:43:54  soap_request:{'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '273', 'soapaction': '"urn:schemas-upnp-org:service:ConnectionManager:2#GetProtocolInfo"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:95)
585 DEBUG soap                        Jun 01 13:43:54  headers: {'x-av-physical-unit-info': 'pa="BRAVIA KDL-40W5500     ";', 'content-length': '273', 'soapaction': '"urn:schemas-upnp-org:service:ConnectionManager:2#GetProtocolInfo"', 'x-av-client-info': 'av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40W5500     "; mv="1.7";', 'user-agent': 'UPnP/1.0 DLNADOC/1.50', 'host': '192.168.1.222:30020', 'content-type': 'text/xml; charset="utf-8"'} (coherence/upnp/core/soap_service.py:132)
586 INFO  soap                        Jun 01 13:43:54  call'GetProtocolInfo' {'soap_methodName': 'GetProtocolInfo'} (coherence/upnp/core/soap_service.py:156)
587 INFO  soap                        Jun 01 13:43:54  soap__genericAction: GetProtocolInfo [required], (2 args) 'coherence.upnp.core.service' {'soap_methodName': 'GetProtocolInfo'} (coherence/upnp/core/service.py:908)
588 DEBUG soap                        Jun 01 13:43:54  get_action_results{} (coherence/upnp/core/service.py:864)
589 INFO  soap                        Jun 01 13:43:54  action_results unsorted'GetProtocolInfo' {'Source': 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000', 'Sink': ''} (coherence/upnp/core/service.py:884)
590 INFO  soap                        Jun 01 13:43:54  action_results sorted'GetProtocolInfo' {'Source': 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000', 'Sink': ''} (coherence/upnp/core/service.py:890)
591 DEBUG soap                        Jun 01 13:43:54  _gotResult{'Source': 'internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000', 'Sink': ''} <POST /a8d9e91f-d924-427a-8bca-bf95ed2c11cc/ConnectionManager/control HTTP/1.1> 'GetProtocolInfo' 'urn:schemas-upnp-org:service:ConnectionManager:2' (coherence/upnp/core/soap_service.py:61)
592 DEBUG soap                        Jun 01 13:43:54  _sendResponse200 '<?xml version="1.0" encoding="utf-8"?><s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><ns0:GetProtocolInfoResponse xmlns:ns0="urn:schemas-upnp-org:service:ConnectionManager:2"><Source>internal:192.168.1.222:audio/mpeg:*,http-get:*:audio/mpeg:*,internal:192.168.1.222:video/mp4:*,http-get:*:video/mp4:*,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:*,http-get:*:video/mpeg:*,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:*,http-get:*:image/jpeg:*,internal:192.168.1.222:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mp4:DLNA.ORG_PN=AVC_TS_BL_CIF15_AAC;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:application/ogg:*,http-get:*:application/ogg:*,internal:192.168.1.222:video/x-msvideo:*,http-get:*:video/x-msvideo:*,internal:192.168.1.222:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01100000000000000000000000000000,internal:192.168.1.222:video/avi:*,http-get:*:video/avi:*,internal:192.168.1.222:video/quicktime:*,http-get:*:video/quicktime:*,internal:192.168.1.222:image/gif:*,http-get:*:image/gif:*,internal:192.168.1.222:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG;DLNA.ORG_OP=01;DLNA.ORG_PS=1;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=00900000000000000000000000000000</Source><Sink /></ns0:GetProtocolInfoResponse></s:Body></s:Envelope>' (coherence/upnp/core/soap_service.py:39)