felix-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Daniel Felsing (JIRA)" <j...@apache.org>
Subject [jira] Commented: (FELIX-642) initial event delivery for new event listeners fails random devices
Date Thu, 24 Jul 2008 18:05:31 GMT

    [ https://issues.apache.org/jira/browse/FELIX-642?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12616558#action_12616558
] 

Daniel Felsing commented on FELIX-642:
--------------------------------------

i have a log which may be valuable...(from the central smart home server which imports the
devices...)
i ran the upnp event tester which is registering the upnpeventlistener to get ALL initial
events.....
i set the log level of the basedriver to 4......
the two lines like -->
uuid:AT-SM-FHZ1-motionsensor1 urn:upnp-org:serviceId:GetEstimate:1EstimatedValue 0
#diff Devices:1
between the other messages of the log are showing the output of the TESTER.....the rest are
logs from basedriver

only 16 of the17 devices have shown initial values...
dunnow how about services...it may be that one of the 16 shown devices has also single services
missing (like already mentioned...its a per service, not per device issue)


==> 72 is the tester device
osgi> start 72debug    20080724 19:54:47 bid#78       - [Importer] deviceNotifyReceived
debug    20080724 19:54:47 bid#78       - 
================REQUEST=====================
NOTIFY * HTTP/1.1
Server: Linux/2.6.20-17-generic UPnP/1.0 CyberDomo/1.8
Cache-Control: max-age=1800
Location: http://192.168.0.11:4020/gen-desc.xml
NTS: ssdp:alive
NT: upnp:rootdevice
USN: uuid:AT-SM-FHZ1-rainsensor1::upnp:rootdevice
HOST: 239.255.255.250:1900

--------------------------------------------
debug    20080724 19:54:47 bid#78       - [Importer] ssdpPacket.isAlive
debug    20080724 19:54:47 bid#78       - [Importer] Device already discovered
debug    20080724 19:54:47 bid#78       - [Importer] deviceNotifyReceived
debug    20080724 19:54:47 bid#78       - 
================REQUEST=====================
NOTIFY * HTTP/1.1
Server: Linux/2.6.20-17-generic UPnP/1.0 CyberDomo/1.8
Cache-Control: max-age=1800
Location: http://192.168.0.11:4020/gen-desc.xml
NTS: ssdp:alive
NT: uuid:AT-SM-FHZ1-rainsensor1
USN: uuid:AT-SM-FHZ1-rainsensor1
HOST: 239.255.255.250:1900

--------------------------------------------
debug    20080724 19:54:47 bid#78       - [Importer] ssdpPacket.isAlive
debug    20080724 19:54:47 bid#78       - [Importer] Device already discovered
debug    20080724 19:54:47 bid#78       - [Importer] deviceNotifyReceived
debug    20080724 19:54:47 bid#78       - 
================REQUEST=====================
NOTIFY * HTTP/1.1
Server: Linux/2.6.20-17-generic UPnP/1.0 CyberDomo/1.8
Cache-Control: max-age=1800
Location: http://192.168.0.11:4020/gen-desc.xml
NTS: ssdp:alive
NT: urn:schemas-upnp-org:device:FHZRainSensor:1
USN: uuid:AT-SM-FHZ1-rainsensor1::urn:schemas-upnp-org:device:FHZRainSensor:1
HOST: 239.255.255.250:1900

--------------------------------------------
debug    20080724 19:54:47 bid#78       - [Importer] ssdpPacket.isAlive
debug    20080724 19:54:47 bid#78       - [Importer] Device already discovered

debug    20080724 19:54:48 bid#78       - [Importer] deviceNotifyReceived
debug    20080724 19:54:48 bid#78       - 
================REQUEST=====================
NOTIFY * HTTP/1.1
Server: Linux/2.6.20-17-generic UPnP/1.0 CyberDomo/1.8
Cache-Control: max-age=1800
Location: http://192.168.0.11:4020/gen-desc.xml
NTS: ssdp:alive
NT: urn:schemas-upnp-org:service:GetName:1
USN: uuid:AT-SM-FHZ1-rainsensor1::urn:schemas-upnp-org:service:GetName:1
HOST: 239.255.255.250:1900

--------------------------------------------
debug    20080724 19:54:48 bid#78       - [Importer] ssdpPacket.isAlive
debug    20080724 19:54:48 bid#78       - [Importer] Device already discovered
debug    20080724 19:54:48 bid#78       - [Importer] check for service updating
info     20080724 19:54:48 bid#72       - [51;org.osgi.service.upnp.UPnPEventListener] ServiceEvent
REGISTERED
debug    20080724 19:54:48 bid#78       - [Importer] serviceChanged
debug    20080724 19:54:48 bid#78       - Event::org.osgi.framework.ServiceEvent[source={org.osgi.service.upnp.UPnPEventListener}={service.id=51}]

osgi> info     20080724 19:54:48 bid#72       - BundleEvent STARTED
CyberDomo message : sock = /192.168.0.11:46700
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:48 bid#78       - [Importer] deviceNotifyReceived
debug    20080724 19:54:48 bid#78       - 
================REQUEST=====================
NOTIFY * HTTP/1.1
Server: Linux/2.6.20-17-generic UPnP/1.0 CyberDomo/1.8
Cache-Control: max-age=1800
Location: http://192.168.0.11:4020/gen-desc.xml
NTS: ssdp:alive
NT: urn:schemas-upnp-org:service:GetStatus:1
USN: uuid:AT-SM-FHZ1-rainsensor1::urn:schemas-upnp-org:service:GetStatus:1
HOST: 239.255.255.250:1900

--------------------------------------------
debug    20080724 19:54:48 bid#78       - [Importer] ssdpPacket.isAlive
debug    20080724 19:54:48 bid#78       - [Importer] Device already discovered
debug    20080724 19:54:48 bid#78       - [Importer] check for service updating
debug    20080724 19:54:48 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:48 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:8fed-a11b-e80b-e0ca
SEQ: 0
Content-Length: 179
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <EstimatedValue>0</EstimatedValue>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:48 bid#78       - [Importer] Notify Request
debug    20080724 19:54:48 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-motionsensor1 urn:upnp-org:serviceId:GetEstimate:1EstimatedValue 0
#diff Devices:1
CyberDomo message : sock = /192.168.0.11:43768
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:48 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:48 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:90b8-a11b-c447-e0f0
SEQ: 0
Content-Length: 160
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Event></Event>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:48 bid#78       - [Importer] Notify Request
debug    20080724 19:54:48 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-motionsensor1 urn:upnp-org:serviceId:GetEvent:1Event 
#diff Devices:1
CyberDomo message : sock = /192.168.0.11:50031
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:48 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:48 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9183-a11b-c553-e08c
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:48 bid#78       - [Importer] Notify Request
debug    20080724 19:54:48 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-dimswitch4 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:2
CyberDomo message : sock = /192.168.0.11:43741
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:49 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:49 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:924a-a11b-ebf6-e072
SEQ: 0
Content-Length: 162
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>34</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:49 bid#78       - [Importer] Notify Request
debug    20080724 19:54:49 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-dimswitch4 urn:upnp-org:serviceId:GetLevel:1Level 34
#diff Devices:2
CyberDomo message : sock = /192.168.0.11:60193
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:49 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:49 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9316-a11b-e0cb-e01d
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:49 bid#78       - [Importer] Notify Request
debug    20080724 19:54:49 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-dimmablelight1 urn:upnp-org:serviceId:SwitchDevice:1Status off
#diff Devices:3
CyberDomo message : sock = /192.168.0.11:56040
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:49 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:49 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:93db-a11b-f0c5-e07b
SEQ: 0
Content-Length: 161
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>0</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:49 bid#78       - [Importer] Notify Request
debug    20080724 19:54:49 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-dimmablelight1 urn:upnp-org:serviceId:DimDevice:1Level 0
#diff Devices:3
CyberDomo message : sock = /192.168.0.11:48067
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:49 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:49 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:94a6-a11b-eb43-e007
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:49 bid#78       - [Importer] Notify Request
debug    20080724 19:54:49 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-switch3 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:4
CyberDomo message : sock = /192.168.0.11:39333
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:49 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:49 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:956d-a11b-fe88-e08e
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:49 bid#78       - [Importer] Notify Request
debug    20080724 19:54:49 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch3 urn:upnp-org:serviceId:GetStatus:1Status off
#diff Devices:5
CyberDomo message : sock = /192.168.0.11:34372
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:50 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:50 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9637-a11b-7b53-e0af
SEQ: 0
Content-Length: 161
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>0</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:50 bid#78       - [Importer] Notify Request
debug    20080724 19:54:50 bid#78       - [Importer] newEventArrived
CyberDomo message : sock = /192.168.0.11:58961
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:50 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:50 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9701-a11b-eafd-e075
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:50 bid#78       - [Importer] Notify Request
debug    20080724 19:54:50 bid#78       - [Importer] newEventArrived
CyberDomo message : sock = /192.168.0.11:50563
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:50 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:50 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:97cb-a11b-3f58-e10b
SEQ: 0
Content-Length: 161
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>0</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:50 bid#78       - [Importer] Notify Request
debug    20080724 19:54:50 bid#78       - [Importer] newEventArrived
CyberDomo message : sock = /192.168.0.11:37457
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:50 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:50 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9893-a11b-c7b3-e082
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:50 bid#78       - [Importer] Notify Request
debug    20080724 19:54:50 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch7 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:6
CyberDomo message : sock = /192.168.0.11:50267
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:50 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:50 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:995d-a11b-2121-e018
SEQ: 0
Content-Length: 163
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>100</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:50 bid#78       - [Importer] Notify Request
debug    20080724 19:54:50 bid#78       - [Importer] newEventArrived
CyberDomo message : sock = /192.168.0.11:42546
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:51 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:51 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9a2a-a11b-b56a-e0a8
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:51 bid#78       - [Importer] Notify Request
debug    20080724 19:54:51 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch4 urn:upnp-org:serviceId:GetStatus:1Status off
#diff Devices:7
CyberDomo message : sock = /192.168.0.11:50869
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:51 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:51 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9afc-a11b-f9e8-e103
SEQ: 0
Content-Length: 161
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>0</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:51 bid#78       - [Importer] Notify Request
debug    20080724 19:54:51 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch4 urn:upnp-org:serviceId:GetLevel:1Level 0
#diff Devices:7
CyberDomo message : sock = /192.168.0.11:35584
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:51 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:51 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9bc2-a11b-a1db-e06c
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:51 bid#78       - [Importer] Notify Request
debug    20080724 19:54:51 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch5 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:8
CyberDomo message : sock = /192.168.0.11:39669
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:51 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:51 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9c8d-a11b-d4e0-e073
SEQ: 0
Content-Length: 163
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>100</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:51 bid#78       - [Importer] Notify Request
debug    20080724 19:54:51 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch5 urn:upnp-org:serviceId:GetLevel:1Level 100
#diff Devices:8
CyberDomo message : sock = /192.168.0.11:44317
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:51 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:51 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9d53-a11b-fdd2-e06a
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:51 bid#78       - [Importer] Notify Request
debug    20080724 19:54:51 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-simplelight1 urn:upnp-org:serviceId:SwitchDevice:1Status on
#diff Devices:9
CyberDomo message : sock = /192.168.0.11:54870
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:52 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:52 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9e1b-a11b-a048-e0be
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:52 bid#78       - [Importer] Notify Request
debug    20080724 19:54:52 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch2 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:10
CyberDomo message : sock = /192.168.0.11:38177
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:52 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:52 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9ee3-a11b-ff74-e0c5
SEQ: 0
Content-Length: 163
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>100</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:52 bid#78       - [Importer] Notify Request
debug    20080724 19:54:52 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch2 urn:upnp-org:serviceId:GetLevel:1Level 100
#diff Devices:10
CyberDomo message : sock = /192.168.0.11:46611
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:52 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:52 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:9fac-a11b-7a23-e116
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:52 bid#78       - [Importer] Notify Request
debug    20080724 19:54:52 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-funksteckdose1 urn:upnp-org:serviceId:SwitchDevice:1Status off
#diff Devices:11
CyberDomo message : sock = /192.168.0.11:49532
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:52 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:52 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a072-a11b-4847-e0d4
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:52 bid#78       - [Importer] Notify Request
debug    20080724 19:54:52 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-dawnsensor2 urn:upnp-org:serviceId:GetStatus:1Status off
#diff Devices:12
CyberDomo message : sock = /192.168.0.11:58959
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:52 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:52 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a13a-a11b-a345-e00a
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:52 bid#78       - [Importer] Notify Request
debug    20080724 19:54:52 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch1 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:13
CyberDomo message : sock = /192.168.0.11:46776
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
CyberDomo message : sock = /192.168.0.11:57175
debug    20080724 19:54:53 bid#78       - [Importer] httpRequestRecieved event
CyberDomo message : httpServThread ...
debug    20080724 19:54:53 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a1fe-a11b-de65-e110
SEQ: 0
Content-Length: 163
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>100</Level>
   </property>
</propertyset>
--------------------------------------------
CyberDomo message : accept ...
debug    20080724 19:54:53 bid#78       - [Importer] Notify Request
debug    20080724 19:54:53 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch1 urn:upnp-org:serviceId:GetLevel:1Level 100
#diff Devices:13
debug    20080724 19:54:53 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:53 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a2c2-a11b-220f-e020
SEQ: 0
Content-Length: 165
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>off</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:53 bid#78       - [Importer] Notify Request
debug    20080724 19:54:53 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-S20_Switch6 urn:upnp-org:serviceId:GetStatus:1Status off
#diff Devices:14
CyberDomo message : sock = /192.168.0.11:52541
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:53 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:53 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a38c-a11b-696e-e0ad
SEQ: 0
Content-Length: 161
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Level>0</Level>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:53 bid#78       - [Importer] Notify Request
debug    20080724 19:54:53 bid#78       - [Importer] newEventArrived
CyberDomo message : sock = /192.168.0.11:54229
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
CyberDomo message : sock = /192.168.0.11:53133
CyberDomo message : httpServThread ...
CyberDomo message : accept ...
debug    20080724 19:54:53 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:53 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a454-a11b-982e-e023
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:53 bid#78       - [Importer] Notify Request
debug    20080724 19:54:53 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-rainsensor1 urn:upnp-org:serviceId:GetStatus:1Status on
#diff Devices:15
debug    20080724 19:54:53 bid#78       - [Importer] httpRequestRecieved event
debug    20080724 19:54:53 bid#78       - 
================REQUEST=====================
NOTIFY /evetSub HTTP/1.0
Content-Type: text/xml; charset="utf-8"
HOST: 192.168.0.5
NT: upnp:event
NTS: upnp:propchange
SID: uuid:a51a-a11b-feda-e08d
SEQ: 0
Content-Length: 164
Connection: close

<?xml version="1.0" encoding="utf-8"?>
<propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
   <property>
      <Status>on</Status>
   </property>
</propertyset>
--------------------------------------------
debug    20080724 19:54:53 bid#78       - [Importer] Notify Request
debug    20080724 19:54:53 bid#78       - [Importer] newEventArrived
uuid:AT-SM-FHZ1-shutter1 urn:upnp-org:serviceId:SwitchDevice:1Status on
#diff Devices:16

> initial event delivery for new event listeners fails random devices
> -------------------------------------------------------------------
>
>                 Key: FELIX-642
>                 URL: https://issues.apache.org/jira/browse/FELIX-642
>             Project: Felix
>          Issue Type: Bug
>          Components: UPnP Subproject
>    Affects Versions: upnp-basedriver-0.8.0
>            Reporter: Daniel Felsing
>            Priority: Blocker
>             Fix For: upnp-basedriver-0.8.0
>
>
> Hi Daniel,
> let's try to summarize this long thread, so that we can get a clear vision on what is
going on, and you can correct my wrong understanding
> Overall Environment Setup
> -------------------------
>  - You have two computer connected togheter by means of a switch. And they are the only
two computer on the network
>  - The first computer runs Felix + UPnP Base Driver
>  - The second computer runs Equinox + UPnP Base Driver + *your* Sensor Base Driver +
Sensor2UPnP Bridge
> Issues
> ------
>  - If you subscribe from the PC running Felix to all the UPnP Device exported by the
UPnP Base Driver running on Equinox and created by your Sensor2UPnP bridge you miss some of
the notification.
> Questions & Tests
> -----------------
>  - Can you create a JIRA issue for your issue, so that it will easy to track and you
will be able to post your code as zip file, instead of copy & paste
>  - During your testing have you always discovered all the device exported by the Equinox
computer in the Felix computer?
>  - Do you miss notification even on the Equinox computer?
> Ciao,
> Stefano "Kismet" Lenzi
> Overall Environment Setup:
> --------------------------
> - yes, connected by a switch
> - the first computer is a UPnP Bridge for a X10 "like" system..i wrote a basedriver and
upnpbridgedriver (bridgedriver is creating upnp devices for every x10 base device). It is
using felix + upnpbasedriver
> - the second computer acts as a server (central control point for a smart home system
implementation) it's using felix also (but for developing eclipse equinox since it's more
comfortable to develop) This second computer uses basedriver to import the devices xposed
by the first computer...and a refinement driver is creating new objects for every upnp device
found (e.g. a light device and so on)
> Issues:
> ------
> - all the devices on the server (so the central smart home controller) that get imported
get refined. They subscribe to the upnp device it refines to get notified on event change.
> Problem is: not all devices refined devices which act as listeners on the SERVER get
notified on INITIAL subscription (when i subscribe the device with felix tester also no notification
is delivered....)...when this happens and i actually change the state of a sensor a notification
is sent (to the device and upnp felix tester) and everything begins to work properly.
> Question & Tests:
> -----------------
> - yes i can :)
> - yes - every device exported by the upnpsensorbridge (=Felix Computer) gets found by
the central server (=equinox computer) It is really just an issue with single "upnpservices".
Here i saw something strange. When i restart the bridgeDriver on Felix computer (exporting
my upnp devices) The "getCurrentValue()" methods get called more and more often the more often
i restart the "bridgeDriver". Is this a normal behavior? It seems to me as state variables
Of the services get mixed up or not cancelled when unregistering or something.
> - i have only one computer where i test the notification -> on the central equinox
server computer.
> On the felix computer exporting my upnp devices i am justwondering about the strange
calls to "getCurrentValue" i described you 
> some code parts that may be for particular interest:
> ---------------------------------------------------------------------
> UPnP Event Tester code on the SERVER side (central server importing the upnp devices
from the second one)
> public class Activator implements BundleActivator {
> 	private ServiceRegistration servReg;
> 	
> 	/*
> 	 * (non-Javadoc)
> 	 * @see org.osgi.framework.BundleActivator#start(org.osgi.framework.BundleContext)
> 	 */
> 	public void start(BundleContext context) throws Exception {
> 		EventListener test = new EventListener();
> 		servReg = context.registerService(UPnPEventListener.class.getName(), test, null);
> 	}
> 	/*
> 	 * (non-Javadoc)
> 	 * @see org.osgi.framework.BundleActivator#stop(org.osgi.framework.BundleContext)
> 	 */
> 	public void stop(BundleContext context) throws Exception {
> 		servReg.unregister();
> 	}
> }
> public class EventListener implements UPnPEventListener {
> 	private ArrayList<String> knownDevs = new ArrayList<String>();
> 	
> 	@Override
> 	public synchronized void notifyUPnPEvent(String deviceId, String serviceId, Dictionary
events) {
> 		String output = "";
> 		output += deviceId + " " + serviceId;
> 		
> 		Enumeration eventit = events.keys();
> 		
> 		while (eventit.hasMoreElements()) {
> 			String key = (String) eventit.nextElement();
> 			output += key + " " + events.get(key) + "\n";
> 		}
> 				
> 		if (!knownDevs.contains(deviceId)) {
> 			knownDevs.add(deviceId);
> 		}
> 		System.err.println(output + "#diff Devices:" + knownDevs.size());
> 		System.err.flush();
> 		
> 	}
> }
> the REFINED devices on the SERVER themselves are using the felix helper class to subscribe
to the events:
> 		// create upnp listener - if evented is true
> 		subscriber = new UPnPSubscriber(bundleContext, this);
> 		subscriber.subscribeAllServicesOf(super.getUPnPDeviceUDN());
> the upnp device implementation on the host exporting the upnp devices are using the following
line to expose event notifier
> 		// setup event notifier
> 		notify_switch = new UPnPEventNotifier(context, this, switchdevswitchservice, switchDev);
> if you need something else please contact me! :)

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


Mime
View raw message