search

mazekkkk / openhab

Automatically exported from code.google.com/p/openhab
0 stars 0 forks source link

Plugwise initiatlisation fails at first run #496

Closed GoogleCodeExporter closed 9 years ago

GoogleCodeExporter commented 9 years ago 
What steps will reproduce the problem?
1. plugwise configuration with about 10 Circles (amount might be relevant)
2. Quartz jobs dont get scheduled
3. "touch" of any of the configuration files solves the issue, configuration is 
re-read, quartz job gets scheduled and plugwise starts successfully

What is the expected output? What do you see instead?
08:55:53.511 INFO  o.o.b.p.i.PlugwiseBinding[:100] - Plugwise added Stick 
connected to serial port /dev/ttyUSB0
08:55:53.675 INFO  o.o.b.p.i.PlugwiseBinding[:157] - Plugwise added Circle+ 
with MAC address: 000D6F000278D1E3
08:55:53.679 ERROR o.o.b.p.internal.CirclePlus[:86] - Error scheduling a Quartz 
Job
08:55:53.710 INFO  o.o.c.s.AbstractActiveService[:169] - Plugwise Refresh 
Service has been started
08:55:53.737 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
08:55:53.761 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F000278D1E3

What version of the product are you using? On what operating system?
snapshot 1.4.0 (both openhab and binding), but behaviour with stable version is 
identical

Please provide any additional information below.
after "touch CasaAmarillo.items" (which is also the expected behaviour):
osgi> 09:04:32.179 INFO  o.o.m.c.i.ModelRepositoryImpl[:97] - Refreshing model 
'CasaAmarillo.items'
09:04:32.878 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:32.902 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F642D
09:04:32.949 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:32.972 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E13
09:04:33.123 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:33.161 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900A4D
09:04:33.279 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:33.303 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F6413
09:04:33.615 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:33.638 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00029009EE
09:04:33.711 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:33.735 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900B1F
09:04:33.839 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:33.870 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E03
09:04:33.876 INFO  runtime.busevents[:26] - EG_TV_TVSwitch state updated to ON
09:04:33.967 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:33.991 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F000278D1E3
09:04:34.103 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:04:34.126 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002901053
09:04:34.178 INFO  runtime.busevents[:26] - KG_HWR2_TrocknerSwitch state 
updated to ON
09:04:34.644 INFO  runtime.busevents[:26] - KG_TEC1_ServerPower state updated 
to 58.535614
09:04:34.647 INFO  runtime.busevents[:26] - EG_Kueche_KuehlschrankSwitch state 
updated to ON
09:04:34.948 INFO  runtime.busevents[:26] - EG_TV_TVPower state updated to 
0.20794633

Original issue reported on code.google.com by Max.Step...@gmx.de on 27 Oct 2013 at 8:06

GoogleCodeExporter commented 9 years ago 
When starting openhab again after a few minutes, same behaviour, but this 
without the error message at "08:55:53.679 ERROR" above:

ReadyNAS:/c/Software/openhab/openhab-1.4/distribution-1.4.0-SNAPSHOT-runtime# 
./start.sh
Launching the openHAB runtime...
osgi> 09:09:14.670 INFO  o.o.c.internal.CoreActivator[:72] - openHAB runtime 
has been started (v1.4.0).
09:09:23.433 INFO  o.o.i.s.i.DiscoveryServiceImpl[:72] - mDNS service has been 
started
09:09:27.337 INFO  o.o.i.r.i.RESTApplication[:138] - Started REST API at /rest
09:09:27.781 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'CasaAmarillo.sitemap'
09:09:28.616 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'logging.persist'
09:09:28.703 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'rrd4j.persist'
09:09:28.743 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'exec.persist'
09:09:28.757 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'db4o.persist'
09:09:28.837 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'CasaAmarillo.items'
09:09:33.964 INFO  o.o.u.w.i.s.WebAppServlet[:79] - Started Classic UI at 
/openhab.app
RXTX Warning:  Removing stale lock file. /var/lock/LCK..ttyUSB0
09:09:38.786 INFO  o.o.b.p.i.PlugwiseBinding[:100] - Plugwise added Stick 
connected to serial port /dev/ttyUSB0
09:09:38.882 INFO  o.o.b.p.i.PlugwiseBinding[:157] - Plugwise added Circle+ 
with MAC address: 000D6F000278D1E3
09:09:38.895 INFO  o.o.c.s.AbstractActiveService[:169] - Plugwise Refresh 
Service has been started
09:09:38.909 INFO  o.o.b.f.i.FritzboxBinding[:215] - Connected to FritzBox on 
fritz.box:1012
09:09:39.056 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:39.093 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F000278D1E3
09:09:39.643 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'CasaAmarillo.rules'
09:09:39.823 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:39.848 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900B1F
09:09:40.126 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:40.185 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900A4D
09:09:40.462 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:40.486 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00029009EE
09:09:40.724 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:40.768 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E13
09:09:40.975 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:40.998 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F6413
09:09:41.218 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0
09:09:41.242 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Destination MAC: 000D6F00028F4E03
09:09:41.439 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:41.463 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F642D
09:09:41.690 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:09:41.714 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002901053

Again, touching any of the configuration files solved this issue and plugwise 
scheduler starts successfully:

09:09:41.714 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002901053
09:16:50.355 INFO  o.o.m.c.i.ModelRepositoryImpl[:97] - Refreshing model 
'CasaAmarillo.items'
09:16:54.022 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0
09:16:54.046 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Destination MAC: 000D6F00028F642D
09:16:54.093 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:54.117 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E13
09:16:54.189 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:54.212 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900A4D
# :16:54.426 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0
09:16:54.452 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error:  APSRequestNodeInfo: Destination MAC:
000D6F00028F6413
09:16:54.733 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:54.756 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00029009EE
09:16:54.804 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:54.827 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900B1F
09:16:54.948 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:54.971 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E03
09:16:55.069 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:55.092 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F000278D1E3
09:16:55.168 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
09:16:55.191 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002901053
09:16:55.269 INFO  runtime.busevents[:26] - EG_TV_TVSwitch state updated to ON
09:16:55.521 INFO  runtime.busevents[:26] - KG_TEC1_ServerPower state updated 
to 60.69499
09:16:55.523 INFO  runtime.busevents[:26] - KG_HWR2_TrocknerSwitch state 
updated to ON
09:16:55.525 INFO  runtime.busevents[:26] - EG_Kueche_KuehlschrankSwitch state 
updated to ON
09:16:55.629 INFO  runtime.busevents[:26] - KG_HWR1_GefrierschrankPower state 
updated to 8.618748
09:16:55.631 INFO  runtime.busevents[:26] - KG_TEC1_ServerSwitch state updated 
to ON
09:16:55.679 INFO  runtime.busevents[:26] - KG_TEC1_RegenwasserPower state 
updated to 2.313685
09:16:55.879 INFO  runtime.busevents[:26] - EG_Kueche_KuehlschrankPower state 
updated to 4.412547
09:16:55.979 INFO  runtime.busevents[:26] - EG_TV_TVPower state updated to 
0.20794633
09:16:56.111 INFO  runtime.busevents[:26] - KG_HWR2_TrocknerPower state updated 
to 0.11716175
09:16:56.336 INFO  runtime.busevents[:26] - EG_WZ_LampePower state updated to 
0.07755748
09:16:56.450 INFO  runtime.busevents[:26] - KG_TEC2_HebeanlagePower state 
updated to 2.3612437
09:16:56.452 INFO  runtime.busevents[:26] - KG_TEC1_RegenwasserSwitch state 
updated to ON
09:16:56.454 INFO  runtime.busevents[:26] - EG_TV_LeselampeSwitch state updated 
to ON

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 8:17

GoogleCodeExporter commented 9 years ago

Original comment by kai.openhab on 27 Oct 2013 at 9:14

GoogleCodeExporter commented 9 years ago 
Max,

I will have a look

Plugwise protocol header error: 000D in message 000D6F0002900B1F is normal, 
this is due to incomplete protocol reverse-engineering.

can you post yr openhab.cfg and .items files in this thread?

K

Original comment by karel.go...@gmail.com on 27 Oct 2013 at 9:49

GoogleCodeExporter commented 9 years ago 
Max

Can you add an entry for the plug wise binding in the logback_debug.xml and 
regenerate the above? I have a hard time figuring out what is happening because 
the initial error related to quartz is something I expect to be due to a 
duplicate Quartz job being scheduled, but that is not possible as I check if a 
given CirclePlus already exists (and if not, a duplicate is not created). Also, 
the binding is "properlyConfigured" after the Stick and the Circleplus (from 
the .cfg) are set-up. 

With respect to the "touching", it is as if the plugwise binding provider is 
not initialised the first time around (which is strange in itself, and maybe 
there the problem is not the plugwise binding). the plugwise binding "scans" at 
each iteration the binding providers and adds Circles if necessary. That's why 
the get "initialised" after touching. Now, there is some protocol output 
("error 000D " etc), so something is happening. Pls try with the debug logging 
on to help me out a bit.

Original comment by karel.go...@gmail.com on 27 Oct 2013 at 10:15

GoogleCodeExporter commented 9 years ago 
Hi Karel,
I'm aware of the 000D issue, that is OK with me. Will turn on debug logging to 
support. Please note that I've also updated the polling interval to 3/5/7/11/ 
or something which dramaticall improved the situation for me.
Max

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 10:37

GoogleCodeExporter commented 9 years ago 
############################## Plugwise Binding 
#######################################
# "stick" is reserved plug wise id
#plugwise:stick.port=COM3
plugwise:stick.port=/dev/ttyUSB0
#plugwise:Stick.mac=000D6F00028F5FD0

# "circleplus" is reserved plug wise id
plugwise:circleplus.mac=000D6F000278D1E3

#plugwise:KG-TEC2-Hebeanlage.mac=000D6F000278D1E3
#plugwise:KG-HWR2-Gefrierschrank.mac=000D6F0002900B1F
#plugwise:EG-Kueche-Kuehlschrank.mac=000D6F0002900A4D
#plugwise:KG-TEC1-Regenwasseranlage.mac=000D6F00029009EE
#plugwise:KG-HWR2-Waeschetrockner.mac=000D6F00028F4E13
#plugwise:KG-TEC1-Serverschrank.mac=000D6F00028F6413
#plugwise:EG-TV-Leselampe.mac=000D6F00028F4E03
#plugwise:EG-TV-TV.mac=000D6F00028F642D
#plugwise:EG-WZ-Lampe.mac=000D6F0002901053

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 10:38

GoogleCodeExporter commented 9 years ago 
Group All
Group:Number:SUM gDG        "Dachgeschoss [%.2f W]"                 (All)
Group:Number:SUM gEG        "Erdgeschoss [%.2f W]"                  (All)
Group:Number:SUM gKG        "Kellergeschoss [%.2f W]"               (All)

Group:Number:SUM DG_BOY     "Sohn [%.2f W]"             <boy1>      (gDG)
Group:Number:SUM DG_GIRL    "Tochter [%.2f W]"          <child1>    (gDG)
Group:Number:SUM DG_GUEST   "Gast [%.2f W]"             <bedroom>   (gDG)
Group:Number:SUM DG_TV      "Fernsehzimmer [%.2f W]"    <video>     (gDG)
Group:Number:SUM DG_BATH    "Badezimmer [%.2f W]"       <bath>      (gDG)

Group:Number:SUM EG_WZ      "Wohnzimmer [%.2f W]"       <video>     (gEG)
Group:Number:SUM EG_KUECHE "Küche [%.2f W]"              <kitchen>   (gEG)
Group:Number:SUM EG_TV      "Fernsehzimmer [%.2f W]"    <video>     (gEG)
Group:Number:SUM EG_Bad     "Badezimmer [%.2f W]"       <bath>      (gEG)
Group:Number:SUM EG_WC      "WC [%.2f W]"               <bath>      (gEG)

Group:Number:SUM KG_HWR1    "HWR1 [%.2f W]"             <bath>      (gKG)
Group:Number:SUM KG_HWR2    "HWR2 [%.2f W]"             <office>    (gKG)
Group:Number:SUM KG_TEC1    "TEC1 [%.2f W]"             <boy1>      (gKG)
Group:Number:SUM KG_TEC2    "TEC2 [%.2f W]"             <bedroom>   (gKG)

// Group:Switch:OR(ON, OFF)     ONOFF   "Alle Schalter [(%d)]"  (All, gKG, gEG, gDG)
// Group:Number:SUM             POWER   "Verbrauch [%.2f W]"    (All, gKG, gEG, gDG)

Group Phones
// Group Power_Chart    (POWER)

/*
 * Plugwise
 */

/* DateTime PlugwiseTime  "Time" <plugwise> { 
plugwise="[000D6F000278D1E3:realtime-clock:10]"} */

Number  KG_TEC2_HebeanlagePower         "Hebeanlage [%.2f W]"       <plugwise>  (KG_TEC2, 
POWER)  { plugwise="[000D6F000278D1E3:power:3]"}
Switch  KG_TEC2_HebeanlageSwitch        "Hebeanlage"                <plugwise>  (KG_TEC2, ONOFF)    { 
plugwise="[ON:000D6F000278D1E3:state:15], [OFF:000D6F000278D1E3:state:15]"}

Number  KG_HWR2_TrocknerPower           "Trockner [%.2f W]"         <plugwise>  (KG_HWR2, 
POWER)  { plugwise="[000D6F00028F4E13:power:5]"}
Switch  KG_HWR2_TrocknerSwitch          "Trockner"                  <plugwise>  (KG_HWR2, ONOFF)    { 
plugwise="[ON:000D6F00028F4E13:state:15], [OFF:000D6F00028F4E13:state:15]"}

Number  KG_TEC1_RegenwasserPower        "Regenwasser [%.2f W]"      <plugwise>  (KG_TEC1, 
POWER)  { plugwise="[000D6F00029009EE:power:7]"}
Switch  KG_TEC1_RegenwasserSwitch       "Regenwasser"               <plugwise>  (KG_TEC1, 
ONOFF)  { plugwise="[ON:000D6F00029009EE:state:15], 
[OFF:000D6F00029009EE:state:15]"}

Number  KG_HWR1_GefrierschrankPower     "Gefrierschrank [%.2f 
W]" <plugwise>  (KG_HWR1, POWER)    { plugwise="[000D6F0002900B1F:power:11]"}
Switch  KG_HWR1_GefrierschrankSwitch    "Gefrierschrank"            <plugwise>  (KG_HWR1, 
ONOFF)  { plugwise="[ON:000D6F0002900B1F:state:15], 
[OFF:000D6F0002900B1F:state:15]"}

Number  EG_Kueche_KuehlschrankPower     "Kühlschrank [%.2f 
W]"     <plugwise>  (EG_KUECHE, POWER)  { plugwise="[000D6F0002900A4D:power:3]"}
Switch  EG_Kueche_KuehlschrankSwitch    "Kühlschrank"                <plugwise>  (EG_KUECHE, 
ONOFF)  { plugwise="[ON:000D6F0002900A4D:state:15], 
[OFF:000D6F0002900A4D:state:15]"}

Number  KG_TEC1_ServerPower             "Server [%.2f W]"           <plugwise>  (KG_TEC1, POWER)    { 
plugwise="[000D6F00028F6413:power:5]"}
Switch  KG_TEC1_ServerSwitch            "Server"                    <plugwise>  (KG_TEC1, ONOFF)    { 
plugwise="[ON:000D6F00028F6413:state:15], [OFF:000D6F00028F6413:state:15]"}

Number  EG_TV_LeselampePower            "Leselampe [%.2f W]"        <plugwise>  (EG_TV, 
POWER)      { plugwise="[000D6F00028F4E03:power:7]"}
Switch  EG_TV_LeselampeSwitch           "Leselampe"                 <plugwise>  (EG_TV, ONOFF)      { 
plugwise="[ON:000D6F00028F4E03:state:15], [OFF:000D6F00028F4E03:state:15]"}

Number  EG_TV_TVPower                   "TV [%.2f W]"               <plugwise>  (EG_TV, POWER)      { 
plugwise="[000D6F00028F642D:power:11]"}
Switch  EG_TV_TVSwitch                  "TV"                        <plugwise>  (EG_TV, ONOFF)      { 
plugwise="[ON:000D6F00028F642D:state:15], [OFF:000D6F00028F642D:state:15]"}

Number  EG_WZ_LampePower                "Dekolampe [%.2f W]"        <plugwise>  (EG_WZ, POWER)      { 
plugwise="[000D6F0002901053:power:13]"}
Switch  EG_WZ_LampeSwitch               "Dekolampe"                 <plugwise>  (EG_WZ, ONOFF)      { 
plugwise="[ON:000D6F0002901053:state:15], [OFF:000D6F0002901053:state:15]"}

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 10:39

GoogleCodeExporter commented 9 years ago 
Hi Karel,

after adding

    <appender name="PLUGWISE" class="ch.qos.logback.core.rolling.RollingFileAppender">
        <file>logs/plugwise.log</file>
        <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
            <!-- weekly rollover and archiving -->
            <fileNamePattern>logs/plugwise-%d{yyyy-ww}.log.zip</fileNamePattern>
            <!-- keep 30 days' worth of history -->
            <maxHistory>30</maxHistory>
        </rollingPolicy>
        <encoder>
            <pattern>%d{yyyy-MM-dd HH:mm:ss} - %msg%n</pattern>
        </encoder>
    </appender>
    <logger name="org.openhab.binding.plugwise" level="DEBUG" additivity="false">
        <appender-ref ref="PLUGWISE" />
        <appender-ref ref="STDOUT" />
    </logger>

to log_back.xml and doing a start.sh I get the following:

ReadyNAS:/c/Software/openhab/openhab-1.4/distribution-1.4.0-SNAPSHOT-runtime/log
s# tail -200f plugwise.log
2013-10-27 12:05:21 - Plugwise binding has been started.
2013-10-27 12:05:21 - Serial port '/dev/ttyUSB0' has been found.
2013-10-27 12:05:21 - Plugwise added Stick connected to serial port /dev/ttyUSB0
2013-10-27 12:05:21 - Sending Plugwise protocol data unit: attempts: 1 MAC: 
command:INITIALISE_REQUEST sequence:0 full HEX:000AB43C
2013-10-27 12:05:21 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26575 payload:00C1
2013-10-27 12:05:21 - Plugwise added Circle+ with MAC address: 000D6F000278D1E3
2013-10-27 12:05:21 - Parsing Plugwise protocol data unit: 
command:INITIALISE_RESPONSE sequence:26575 
payload:000D6F00028F5FD00101440D6F000278D1E37444FF
2013-10-27 12:05:21 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F000278D1E331AA
2013-10-27 12:05:21 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26576 payload:00C1
2013-10-27 12:05:21 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F000278D1E362C0
2013-10-27 12:05:22 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:22 - Plugwise protocol header error: 000D in message 
000D6F000278D1E3
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26577 payload:00C1
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26576 
payload:000D6F000278D1E30D0A94D90004D83801856539070073274E0843A901
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26577 
payload:000D6F000278D1E33F803D04B5F628C13DD7E55300000000
2013-10-27 12:05:22 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:CLOCK_SET_REQUEST sequence:0 full 
HEX:0016000D6F000278D1E30D0A94D9FFFFFFFF0B0516065ED2
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26578 payload:00C1
2013-10-27 12:05:22 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E300AD29
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26579 payload:00C1
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26578 payload:00D7000D6F000278D1E3
2013-10-27 12:05:22 - Plugwise Unknown Acknowledgement message Extension
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26579 
payload:000D6F000278D1E3000D6F0002900B1F00
2013-10-27 12:05:22 - Added a Circle with MAC 000D6F0002900B1F to the cache
2013-10-27 12:05:22 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F0002900B1F command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F0002900B1F5057
2013-10-27 12:05:22 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26580 payload:00C1
2013-10-27 12:05:22 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F0002900B1F command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F0002900B1F033D
2013-10-27 12:05:22 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:22 - Plugwise protocol header error: 000D in message 
000D6F0002900B1F
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26581 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E301BD08
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26582 payload:00C1
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26580 
payload:000D6F0002900B1F0D0A94D70004D83801856539070140264E0844C202
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26581 
payload:000D6F0002900B1F3F7D4579B59CEC143DACAE0700000000
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26582 
payload:000D6F000278D1E3000D6F0002900A4D01
2013-10-27 12:05:23 - Added a Circle with MAC 000D6F0002900A4D to the cache
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F0002900A4D command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F0002900A4DD6B0
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26583 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F0002900A4D command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F0002900A4D85DA
2013-10-27 12:05:23 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:23 - Plugwise protocol header error: 000D in message 
000D6F0002900A4D
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26584 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E3028D6B
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26585 payload:00C1
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26583 
payload:000D6F0002900A4D0D0A94D70004D83001856539070140264E0844C202
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26584 
payload:000D6F0002900A4D3F80708CB5D10FA13D7F62C800000000
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26585 
payload:000D6F000278D1E3000D6F00029009EE02
2013-10-27 12:05:23 - Added a Circle with MAC 000D6F00029009EE to the cache
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00029009EE command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F00029009EE8C50
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26586 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00029009EE command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F00029009EEDF3A
2013-10-27 12:05:23 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:23 - Plugwise protocol header error: 000D in message 
000D6F00029009EE
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26587 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E3039D4A
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26586 
payload:000D6F00029009EE0D0A94D70004D82801856539070140264E0844C202
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26587 
payload:000D6F00029009EE3F81189EB6193B083D9C7F3900000000
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26588 payload:00C1
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26588 
payload:000D6F000278D1E3000D6F00028F4E1303
2013-10-27 12:05:23 - Added a Circle with MAC 000D6F00028F4E13 to the cache
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F4E13 command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F00028F4E13A4A7
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26589 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F4E13 command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F00028F4E13F7CD
2013-10-27 12:05:23 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:23 - Plugwise protocol header error: 000D in message 
000D6F00028F4E13
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26590 payload:00C1
2013-10-27 12:05:23 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E304EDAD
2013-10-27 12:05:23 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26589 
payload:000D6F00028F4E130D0A94D70004D83001856539070140264E0844C202
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26591 payload:00C1
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26590 
payload:000D6F00028F4E133F7FC333B59688693D610A8000000000
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26591 
payload:000D6F000278D1E3000D6F00028F641304
2013-10-27 12:05:24 - Added a Circle with MAC 000D6F00028F6413 to the cache
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F6413 command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F00028F6413A6F7
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26592 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F6413 command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F00028F6413F59D
2013-10-27 12:05:24 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:24 - Plugwise protocol header error: 000D in message 
000D6F00028F6413
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26593 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E305FD8C
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26592 
payload:000D6F00028F64130D0A94D70004D83801856539070140264E0844C202
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26593 
payload:000D6F00028F64133F81A08EB5EDBD0D3DDC7E0200000000
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26594 payload:00C1
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26594 
payload:000D6F000278D1E3000D6F00028F4E0305
2013-10-27 12:05:24 - Added a Circle with MAC 000D6F00028F4E03 to the cache
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F4E03 command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F00028F4E039796
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26595 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F4E03 command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F00028F4E03C4FC
2013-10-27 12:05:24 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:24 - Plugwise protocol header error: 000D in message 
000D6F00028F4E03
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26596 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E306CDEF
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26595 
payload:000D6F00028F4E030D0A94D70004D82801856539070140264E0844C202
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26596 
payload:000D6F00028F4E033F7E5A32B5DE530A3D1E050B00000000
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26597 payload:00C1
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26597 
payload:000D6F000278D1E3000D6F00028F642D06
2013-10-27 12:05:24 - Added a Circle with MAC 000D6F00028F642D to the cache
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F642D command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F00028F642DFDD4
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26598 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F00028F642D command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F00028F642DAEBE
2013-10-27 12:05:24 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:24 - Plugwise protocol header error: 000D in message 
000D6F00028F642D
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26599 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E307DDCE
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26598 
payload:000D6F00028F642D0D0A94D70004D83001856539070140264E0844C202
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26600 payload:00C1
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26599 
payload:000D6F00028F642D3F802058B5A089E33DC7B57100000000
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26600 
payload:000D6F000278D1E3000D6F000290105307
2013-10-27 12:05:24 - Added a Circle with MAC 000D6F0002901053 to the cache
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F0002901053 command:DEVICE_INFORMATION_REQUEST sequence:0 full 
HEX:0023000D6F0002901053727D
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26601 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F0002901053 command:DEVICE_CALIBRATION_REQUEST sequence:0 full 
HEX:0026000D6F00029010532117
2013-10-27 12:05:24 - Plugwise protocol message error: # APSRequestNodeInfo: 
Source MAC: 000D6F00028F5FD0# APSRequestNodeInfo: Destination MAC:
2013-10-27 12:05:24 - Plugwise protocol header error: 000D in message 
000D6F0002901053
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26602 payload:00C1
2013-10-27 12:05:24 - Sending Plugwise protocol data unit: attempts: 1 
MAC:000D6F000278D1E3 command:DEVICE_ROLECALL_REQUEST sequence:0 full 
HEX:0018000D6F000278D1E3082C21
2013-10-27 12:05:24 - Parsing Plugwise protocol data unit: 
command:DEVICE_INFORMATION_RESPONSE sequence:26601 
payload:000D6F00029010530D0A94D70004D82801856539070140264E0844C202
2013-10-27 12:05:25 - Parsing Plugwise protocol data unit: 
command:DEVICE_CALIBRATION_RESPONSE sequence:26602 
payload:000D6F00029010533F80BD8EB5C28B523D14F86200000000
2013-10-27 12:05:25 - Parsing Plugwise protocol data unit: 
command:ACKNOWLEDGEMENT sequence:26603 payload:00C1
2013-10-27 12:05:25 - Parsing Plugwise protocol data unit: 
command:DEVICE_ROLECALL_RESPONSE sequence:26603 
payload:000D6F000278D1E3FFFFFFFFFFFFFFFF08

I will now wait for some minutes whether it "catched up" on its own, then touch 
one of the config files and post plugwise debug output again.

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 11:09

GoogleCodeExporter commented 9 years ago 
Mhh... it seems that is "discovering" all the devices in the network, so that 
is good. Do you see anything in your logs (before or after the section you 
copy/pasted) that indicates .item parsing?

the polling interval stuff is a different issue (e.g. Stick flooding the 
network too fast, need to "pace" messages sent on the network).

Original comment by karel.go...@gmail.com on 27 Oct 2013 at 11:18

GoogleCodeExporter commented 9 years ago 
after that nothing happens anymore (haven't "touched" yet), below is the full 
output to stdout. It is reading the model, but on top of that I dont see eg 
parsing the items. Is there another log level I should turn on ?

I can also provide the start_debug.sh output if you prefer, however I would 
turn off persistence logging befor that. For simplicity i have a strategy for 
"*" in my persistence config currently which stores all values every minute... 
Somehow I can't attach files here (issue attachment storage quote exceeded), 
otherwise I would attach the full zipped logs.

ReadyNAS:/c/Software/openhab/openhab-1.4/distribution-1.4.0-SNAPSHOT-runtime# 
./start.sh
Launching the openHAB runtime...
osgi> 12:04:56.173 INFO  o.o.c.internal.CoreActivator[:72] - openHAB runtime 
has been started (v1.4.0).
12:05:06.513 INFO  o.o.i.s.i.DiscoveryServiceImpl[:72] - mDNS service has been 
started
12:05:10.042 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'CasaAmarillo.sitemap'
12:05:11.322 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'logging.persist'
12:05:11.457 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'rrd4j.persist'
12:05:11.515 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'exec.persist'
12:05:11.544 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'db4o.persist'
12:05:11.691 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'CasaAmarillo.items'
12:05:11.789 INFO  o.o.i.r.i.RESTApplication[:138] - Started REST API at /rest
12:05:17.274 INFO  o.o.u.w.i.s.WebAppServlet[:79] - Started Classic UI at 
/openhab.app
12:05:21.272 DEBUG o.o.b.p.i.PlugwiseActivator[:33] - Plugwise binding has been 
started.
12:05:21.422 ERROR o.o.b.b.i.BTDeviceDiscoveryService[:217] - Error while 
initializing local bluetooth device.
javax.bluetooth.BluetoothStateException: BlueCove 
com.intel.bluetooth.BluetoothStackBlueZ|com.intel.bluetooth.BluetoothStackBlueZD
Bus not available
        at com.intel.bluetooth.BlueCoveImpl.loadStackClass(BlueCoveImpl.java:356)
RXTX Warning:  Removing stale lock file. /var/lock/LCK..ttyUSB0
12:05:21.696 DEBUG o.o.b.plugwise.internal.Stick[:179] - Serial port 
'/dev/ttyUSB0' has been found.
12:05:21.827 INFO  o.o.b.p.i.PlugwiseBinding[:100] - Plugwise added Stick 
connected to serial port /dev/ttyUSB0
12:05:21.861 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC: command:INITIALISE_REQUEST sequence:0 full 
HEX:000AB43C
12:05:21.880 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26575 payload:00C1
12:05:21.925 INFO  o.o.b.p.i.PlugwiseBinding[:157] - Plugwise added Circle+ 
with MAC address: 000D6F000278D1E3
12:05:21.927 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:INITIALISE_RESPONSE sequence:26575 
payload:000D6F00028F5FD00101440D6F000278D1E37444FF
12:05:21.932 INFO  o.o.c.s.AbstractActiveService[:169] - Plugwise Refresh 
Service has been started
12:05:21.945 INFO  o.o.b.f.i.FritzboxBinding[:215] - Connected to FritzBox on 
fritz.box:1012
12:05:21.976 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F000278D1E331AA
12:05:21.986 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26576 payload:00C1
12:05:21.990 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F000278D1E362C0
12:05:22.016 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:22.044 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F000278D1E3
12:05:22.068 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26577 payload:00C1
12:05:22.100 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26576 
payload:000D6F000278D1E30D0A94D90004D83801856539070073274E0843A901
12:05:22.146 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26577 
payload:000D6F000278D1E33F803D04B5F628C13DD7E55300000000
12:05:22.765 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 command:CLOCK_SET_REQUEST 
sequence:0 full HEX:0016000D6F000278D1E30D0A94D9FFFFFFFF0B0516065ED2
12:05:22.794 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26578 payload:00C1
12:05:22.804 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E300AD29
12:05:22.818 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26579 payload:00C1
12:05:22.887 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26578 
payload:00D7000D6F000278D1E3
12:05:22.892 DEBUG o.o.b.plugwise.internal.Stick[:535] - Plugwise Unknown 
Acknowledgement message Extension
12:05:22.913 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26579 
payload:000D6F000278D1E3000D6F0002900B1F00
12:05:22.922 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F0002900B1F to the cache
12:05:22.929 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F0002900B1F 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F0002900B1F5057
12:05:22.940 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26580 payload:00C1
12:05:22.950 INFO  o.o.m.c.i.ModelRepositoryImpl[:79] - Loading model 
'CasaAmarillo.rules'
12:05:22.949 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F0002900B1F 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F0002900B1F033D
12:05:22.974 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:22.998 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900B1F
12:05:23.022 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26581 payload:00C1
12:05:23.026 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E301BD08
12:05:23.048 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26582 payload:00C1
12:05:23.081 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26580 
payload:000D6F0002900B1F0D0A94D70004D83801856539070140264E0844C202
12:05:23.109 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26581 
payload:000D6F0002900B1F3F7D4579B59CEC143DACAE0700000000
12:05:23.179 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26582 
payload:000D6F000278D1E3000D6F0002900A4D01
12:05:23.222 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F0002900A4D to the cache
12:05:23.226 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F0002900A4D 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F0002900A4DD6B0
12:05:23.236 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26583 payload:00C1
12:05:23.243 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F0002900A4D 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F0002900A4D85DA
12:05:23.268 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:23.292 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002900A4D
12:05:23.316 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26584 payload:00C1
12:05:23.320 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E3028D6B
12:05:23.340 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26585 payload:00C1
12:05:23.368 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26583 
payload:000D6F0002900A4D0D0A94D70004D83001856539070140264E0844C202
12:05:23.437 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26584 
payload:000D6F0002900A4D3F80708CB5D10FA13D7F62C800000000
12:05:23.498 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26585 
payload:000D6F000278D1E3000D6F00029009EE02
12:05:23.521 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F00029009EE to the cache
12:05:23.525 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00029009EE 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F00029009EE8C50
12:05:23.537 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26586 payload:00C1
12:05:23.540 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00029009EE 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F00029009EEDF3A
12:05:23.573 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:23.602 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00029009EE
12:05:23.629 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26587 payload:00C1
12:05:23.649 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E3039D4A
12:05:23.662 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26586 
payload:000D6F00029009EE0D0A94D70004D82801856539070140264E0844C202
12:05:23.703 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26587 
payload:000D6F00029009EE3F81189EB6193B083D9C7F3900000000
12:05:23.728 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26588 payload:00C1
12:05:23.766 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26588 
payload:000D6F000278D1E3000D6F00028F4E1303
12:05:23.821 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F00028F4E13 to the cache
12:05:23.875 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F4E13 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F00028F4E13A4A7
12:05:23.886 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26589 payload:00C1
12:05:23.889 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F4E13 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F00028F4E13F7CD
12:05:23.913 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:23.937 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E13
12:05:23.960 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26590 payload:00C1
12:05:23.963 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E304EDAD
12:05:23.988 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26589 
payload:000D6F00028F4E130D0A94D70004D83001856539070140264E0844C202
12:05:24.045 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26591 payload:00C1
12:05:24.076 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26590 
payload:000D6F00028F4E133F7FC333B59688693D610A8000000000
12:05:24.101 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26591 
payload:000D6F000278D1E3000D6F00028F641304
12:05:24.171 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F00028F6413 to the cache
12:05:24.176 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F6413 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F00028F6413A6F7
12:05:24.185 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26592 payload:00C1
12:05:24.188 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F6413 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F00028F6413F59D
12:05:24.212 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:24.236 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F6413
12:05:24.260 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26593 payload:00C1
12:05:24.264 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E305FD8C
12:05:24.286 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26592 
payload:000D6F00028F64130D0A94D70004D83801856539070140264E0844C202
12:05:24.312 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26593 
payload:000D6F00028F64133F81A08EB5EDBD0D3DDC7E0200000000
12:05:24.337 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26594 payload:00C1
12:05:24.362 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26594 
payload:000D6F000278D1E3000D6F00028F4E0305
12:05:24.372 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F00028F4E03 to the cache
12:05:24.376 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F4E03 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F00028F4E039796
12:05:24.386 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26595 payload:00C1
12:05:24.388 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F4E03 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F00028F4E03C4FC
12:05:24.413 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:24.436 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F4E03
12:05:24.459 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26596 payload:00C1
12:05:24.462 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E306CDEF
12:05:24.485 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26595 
payload:000D6F00028F4E030D0A94D70004D82801856539070140264E0844C202
12:05:24.517 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26596 
payload:000D6F00028F4E033F7E5A32B5DE530A3D1E050B00000000
12:05:24.541 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26597 payload:00C1
12:05:24.565 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26597 
payload:000D6F000278D1E3000D6F00028F642D06
12:05:24.571 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F00028F642D to the cache
12:05:24.576 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F642D 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F00028F642DFDD4
12:05:24.589 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26598 payload:00C1
12:05:24.592 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F00028F642D 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F00028F642DAEBE
12:05:24.616 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:24.640 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F00028F642D
12:05:24.667 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26599 payload:00C1
12:05:24.669 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E307DDCE
12:05:24.702 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26598 
payload:000D6F00028F642D0D0A94D70004D83001856539070140264E0844C202
12:05:24.727 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26600 payload:00C1
12:05:24.752 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26599 
payload:000D6F00028F642D3F802058B5A089E33DC7B57100000000
12:05:24.777 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26600 
payload:000D6F000278D1E3000D6F000290105307
12:05:24.821 DEBUG o.o.b.p.internal.CirclePlus[:129] - Added a Circle with MAC 
000D6F0002901053 to the cache
12:05:24.825 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F0002901053 
command:DEVICE_INFORMATION_REQUEST sequence:0 full HEX:0023000D6F0002901053727D
12:05:24.855 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26601 payload:00C1
12:05:24.857 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F0002901053 
command:DEVICE_CALIBRATION_REQUEST sequence:0 full HEX:0026000D6F00029010532117
12:05:24.881 ERROR o.o.b.plugwise.internal.Stick[:457] - Plugwise protocol 
message error: # APSRequestNodeInfo: Source MAC: 000D6F00028F5FD0# 
APSRequestNodeInfo: Destination MAC:
12:05:24.904 ERROR o.o.b.plugwise.internal.Stick[:454] - Plugwise protocol 
header error: 000D in message 000D6F0002901053
12:05:24.928 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26602 payload:00C1
12:05:24.966 DEBUG o.o.b.plugwise.internal.Stick[:668] - Sending Plugwise 
protocol data unit: attempts: 1 MAC:000D6F000278D1E3 
command:DEVICE_ROLECALL_REQUEST sequence:0 full HEX:0018000D6F000278D1E3082C21
12:05:24.990 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_INFORMATION_RESPONSE sequence:26601 
payload:000D6F00029010530D0A94D70004D82801856539070140264E0844C202
12:05:25.022 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_CALIBRATION_RESPONSE sequence:26602 
payload:000D6F00029010533F80BD8EB5C28B523D14F86200000000
12:05:25.046 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:ACKNOWLEDGEMENT sequence:26603 payload:00C1
12:05:25.071 DEBUG o.o.b.plugwise.internal.Stick[:401] - Parsing Plugwise 
protocol data unit: command:DEVICE_ROLECALL_RESPONSE sequence:26603 
payload:000D6F000278D1E3FFFFFFFFFFFFFFFF08

osgi>
osgi>

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 11:32

GoogleCodeExporter commented 9 years ago 
PS: That one here is coming during each start, somehow the plugwise binding is 
not fully releasing the device. That is no issue though currently as it is 
detected/removed at each startup:

RXTX Warning:  Removing stale lock file. /var/lock/LCK..ttyUSB0

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 11:34

GoogleCodeExporter commented 9 years ago 
Are you fluent with Eclipse and its debugger?

would be helpful if you could see what happens in execute() of the 
PlugwiseBinding class.

Original comment by karel.go...@gmail.com on 27 Oct 2013 at 11:36

GoogleCodeExporter commented 9 years ago 
Unfortunately not... Took a look at the sources yesterday, but my programming 
days where almost 20 years ago in pascal and C, since then only "dirty hacking" 
shell scripts sometimes... I could give it a try next weekend though.

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 11:56

GoogleCodeExporter commented 9 years ago 
Eclipse Standard is the package to go for, and the follow the instructions on 
the openhab homepage for contributors, correct ?

Original comment by Max.Step...@gmx.de on 27 Oct 2013 at 12:06

GoogleCodeExporter commented 9 years ago

Original comment by teichsta on 5 Nov 2013 at 10:53

GoogleCodeExporter commented 9 years ago 
This issue has been migrated to Github. If this issue id is greater than103 its 
id has been preserved on Github. You can open your issue by calling the URL 
https://github.com/openhab/openhab/issues/<issueid>. Issues with ids less or 
equal 103 new ids were created.

Original comment by teichsta on 17 Nov 2013 at 8:08

GoogleCodeExporter commented 9 years ago 
Set status to "invalid" to show that these issues are not tracked here anymore 
- please refer to GitHub instead!

Original comment by kai.openhab on 2 Dec 2013 at 7:12