Fibaro Motion Sensor problems - maybe related to Aeon ZSTICK (GEN5) - missing configuration?

Zvika · August 15, 2016, 9:29am

Hi.
I have an Aeon ZSTICK (GEN5).
Generally, it’s working OK.

However, according to the manual (last page), there are some configurations available.
But I don’t see any configuration section in my HABmin GUI.

Specifically, I’m interested in the Security network - which interestingly is disabled by default.

chris · August 15, 2016, 9:54am

Despite what the manual may say, I believe that it is enabled by default (it certainly was on my stick).

While I’ve never tried to configure these sticks, I read on another forum that this is only possible over the air, so I’ve not added the configuration to OH.

If this is incorrect, then we can reasonably easily add the configuration (I think), but I’d like to confirm this is the case.

Zvika · August 15, 2016, 10:02am

How can I check if security is enabled?

I’m trying to understand what’s going on with my Fibaro Motion Sensor, and my problem seems like this thread. I agree with what you wrote there, that it probably should be solved without a specific hack.

I got a hunch that the problem is related to the difference between “Motion/Tamper” groups - and “Motion BC/Tamper BC” groups.
When I’ve used the ‘Z PLUS’ groups, I got same alarms values on start and end.
When I’ve used the ‘Backward compatible’ groups - it seems better. (at that point, I had to stop, so I’m not 100% that it’s working now).

That implies that either Fibaro or Aeon has some problem with ZPLUS; or that some configuration is needed.

chris · August 15, 2016, 10:09am

I very much doubt this will be related to security since the binding doesn’t support security. If you are trying to use the secure classes, it won’t work.

You don’t actually say what the problem is? Maybe you can explain this and also provide a log?

What do you mean by ZPlus groups and Backward compatible groups? I wasn’t aware there were two sets of groups in this device so I’m not sure what you mean here - sorry.

Zvika · August 15, 2016, 10:25am

Citing from the Fibaro manual:

The Motion Sensor provides the association of four groups:

1st association group – “Lifeline” reports the device status and allows
for assigning single device only (main controller by default).

2nd association group – “Motion” is assigned to the motion sensor

sends motion detection and alarm cancellation frames to the associated
devices.

3rd association group – “Tamper” is assigned to the tamper - sends
tamper alarm and alarm cancellation frames to the associated devices.

4th association group – “Motion BC” is assigned to the motion sensor

sends motion detection and alarm cancellation frames to the
associated devices. Provides backward compatibility with controllers
not supporting Z-Wave+ protocol.

5th association group – “Tamper BC” is assigned to the tamper -
sends tamper alarm and alarm cancellation frames to the associated
devices. Provides backward compatibility with controllers not supporting
Z-Wave+ protocol.

(let’s ignore the typo in the heading, that says there are four groups, and then count five…)

Notice the similarity between 2nd and 4th; 3rd and 5th.
‘Motion’ and ‘Tamper’ are each duplicated - one is ‘regular ZPLUS’ and one is ‘Backward Compatible’.

My problem (and probably the problem in the aforementioned thread) is:

17:54:12.786 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:57:28.740 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:57:28.740 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class SENSOR_MULTILEVEL
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:95   ] - NODE 4: Received Sensor Multi Level Request
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:130  ] - NODE 4: Sensor Multi Level REPORT received
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:145  ] - NODE 4: Sensor Type = Temperature(1), Scale = 0
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:154  ] - NODE 4: Sensor Value = 24.4
17:57:28.740 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 24.4
17:58:06.090 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.090 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:06.090 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:06.090 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:06.090 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:06.105 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:06.163 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.163 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:06.163 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:06.163 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:06.163 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:06.163 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0

For Tamper (or maybe it was Motion - I forgot…) there were sent two identical ALARM with value 0, at beginning of the alarm, and on the end of the alarm - so there is no way to distinguish between them.

This was achieved with the contoller being in the 2nd and 3rd groups (“ZPLUS”). However, when I removed it from these, and add it to the 4th and 5th groups (“BC”) - it looked better - but at that point I had to go, so I will be certain only later.

BTW,
there are some other weird things in the log:

2016-08-15 09:39:40.335 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class ZWAVE_PLUS_INFO
2016-08-15 09:39:40.337 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class DEVICE_RESET_LOCALLY
2016-08-15 09:39:40.338 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class ASSOCIATION_GROUP_INFO
2016-08-15 09:39:40.340 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class POWERLEVEL
2016-08-15 09:39:40.343 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class MULTI_INSTANCE_ASSOCIATION
2016-08-15 09:39:40.346 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class SECURITY
2016-08-15 09:39:40.347 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class FIRMWARE_UPDATE_MD
2016-08-15 09:39:40.376 [WARN ] [b.z.i.p.s.SendDataMessageClass] - NODE 5: Already processed another send data request for this callback Id, ignoring.
2016-08-15 09:39:45.353 [ERROR] [WaveController$ZWaveSendThread] - NODE 5: Timeout while sending message. Requeueing - 0 attempts left!
2016-08-15 09:39:53.444 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:40:11.972 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@a21797, ignoring.
2016-08-15 09:40:12.012 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1076319, ignoring.
2016-08-15 09:40:12.054 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@d70123, ignoring.
...

And last warning continues with a lot of similar prints.

chris · August 15, 2016, 11:19am

I don’t think this is really related to ZWave plus. I suspect that they are sending different command classes with the two groups - one might use the old alarm class, and one might use the new(er) notification class, but that’s a guess and without seeing a log of the data, I can’t really tell.

Please can you provide the log that isn’t filtered - I need all the information and currently I’m missing the most important lines which is what is actually received. The issue might be related to the NOTIFICATION command class, but I need to see the data.

Don’t worry about the warnings you’ve highlighted about the CONFIGURATION class items - this is ok, and I really should remove this message.

For the missing command classes - these won’t be implemented so again, you can ignore these

Zvika · August 15, 2016, 11:43am

Here’s a grepped log, of using “regular” groups:

17:52:58.625 [INFO ] [rialApiGetInitDataMessageClass:60   ] - NODE 4: Node found
17:52:58.634 [DEBUG] [b.z.i.protocol.ZWaveController:506  ] - NODE 4: Init node thread start
17:52:58.899 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:138  ] - NODE 4: Serializing from file etc\zwave\node4.xml
17:52:58.969 [DEBUG] [b.z.i.protocol.ZWaveController:539  ] - NODE 4: Restore from config: Ok.
17:52:58.970 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267  ] - NODE 4: Node advancer - EMPTYNODE: queue length(0), free to send(true)
17:52:58.972 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
17:52:58.973 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:337  ] - NODE 4: Node advancer: Initialisation starting
17:52:58.975 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890  ] - NODE 4: Node advancer - advancing to PROTOINFO
17:52:58.977 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
17:52:58.979 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:346  ] - NODE 4: Node advancer: PROTOINFO - send IdentifyNode
17:52:58.983 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240  ] - NODE 4: Node advancer - queued packet. Queue length is 1
17:52:58.983 [DEBUG] [b.z.i.protocol.ZWaveController:595  ] - NODE 4: Init node thread finished
17:52:59.058 [DEBUG] [i.p.s.IdentifyNodeMessageClass:56   ] - NODE 4: ProtocolInfo
17:52:59.058 [DEBUG] [i.p.s.IdentifyNodeMessageClass:72   ] - NODE 4: Listening = false
17:52:59.059 [DEBUG] [i.p.s.IdentifyNodeMessageClass:73   ] - NODE 4: Routing = true
17:52:59.060 [DEBUG] [i.p.s.IdentifyNodeMessageClass:74   ] - NODE 4: Beaming = true
17:52:59.060 [DEBUG] [i.p.s.IdentifyNodeMessageClass:75   ] - NODE 4: Version = 4
17:52:59.061 [DEBUG] [i.p.s.IdentifyNodeMessageClass:76   ] - NODE 4: FLIRS = false
17:52:59.061 [DEBUG] [i.p.s.IdentifyNodeMessageClass:77   ] - NODE 4: Security = false
17:52:59.062 [DEBUG] [i.p.s.IdentifyNodeMessageClass:78   ] - NODE 4: Max Baud = 40000
17:52:59.062 [DEBUG] [i.p.s.IdentifyNodeMessageClass:94   ] - NODE 4: Basic = Routing Slave
17:52:59.062 [DEBUG] [i.p.s.IdentifyNodeMessageClass:102  ] - NODE 4: Generic = Garage Door
17:52:59.063 [DEBUG] [i.p.s.IdentifyNodeMessageClass:110  ] - NODE 4: Specific = Simple Garage Door
17:52:59.064 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243  ] - NODE 4: Creating new instance of command class NO_OPERATION
17:52:59.064 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243  ] - NODE 4: Creating new instance of command class BASIC
17:52:59.070 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034 ] - NODE 4: Node advancer - PROTOINFO: Transaction complete (IdentifyNode:Request) success(true)
17:52:59.071 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202  ] - NODE 4: Node advancer - checking initialisation queue. Queue size 1.
17:52:59.072 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208  ] - NODE 4: Node advancer - message removed from queue. Queue size 0.
17:52:59.072 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267  ] - NODE 4: Node advancer - PROTOINFO: queue length(0), free to send(true)
17:52:59.074 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - PROTOINFO try 1: stageAdvanced(false)
17:52:59.075 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890  ] - NODE 4: Node advancer - advancing to NEIGHBORS
17:52:59.075 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - NEIGHBORS try 0: stageAdvanced(true)
17:52:59.076 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:356  ] - NODE 4: Node advancer: NEIGHBORS - send RoutingInfo
17:52:59.077 [DEBUG] [p.s.GetRoutingInfoMessageClass:30   ] - NODE 4: Request routing info
17:52:59.078 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240  ] - NODE 4: Node advancer - queued packet. Queue length is 1
17:52:59.166 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Temp is not completed initialisation
17:52:59.167 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Alarm is not completed initialisation
17:52:59.168 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Bat is not completed initialisation
17:52:59.169 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Lux is not completed initialisation
17:52:59.170 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Movement is not completed initialisation
17:52:59.229 [DEBUG] [p.s.GetRoutingInfoMessageClass:49   ] - NODE 4: Got NodeRoutingInfo request.
17:52:59.229 [DEBUG] [p.s.GetRoutingInfoMessageClass:79   ] - NODE 4: Neighbor nodes: 1
17:52:59.238 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034 ] - NODE 4: Node advancer - NEIGHBORS: Transaction complete (GetRoutingInfo:Request) success(true)
17:52:59.240 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202  ] - NODE 4: Node advancer - checking initialisation queue. Queue size 1.
17:52:59.243 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208  ] - NODE 4: Node advancer - message removed from queue. Queue size 0.
17:52:59.244 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267  ] - NODE 4: Node advancer - NEIGHBORS: queue length(0), free to send(true)
17:52:59.246 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - NEIGHBORS try 1: stageAdvanced(false)
17:52:59.247 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890  ] - NODE 4: Node advancer - advancing to FAILED_CHECK
17:52:59.249 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - FAILED_CHECK try 0: stageAdvanced(true)
17:52:59.250 [DEBUG] [i.p.s.IsFailedNodeMessageClass:32   ] - NODE 4: Requesting IsFailedNode status from controller.
17:52:59.252 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240  ] - NODE 4: Node advancer - queued packet. Queue length is 1
17:52:59.338 [WARN ] [i.p.s.IsFailedNodeMessageClass:53   ] - NODE 4: Is currently marked as failed by the controller!
17:52:59.340 [DEBUG] [.z.internal.protocol.ZWaveNode:260  ] - NODE 4: Node is DEAD.
17:52:59.341 [DEBUG] [.z.internal.protocol.ZWaveNode:269  ] - NODE 4: Initialisation incomplete, not signalling state change.
17:52:59.344 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034 ] - NODE 4: Node advancer - FAILED_CHECK: Transaction complete (IsFailedNodeID:Request) success(true)
17:52:59.345 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202  ] - NODE 4: Node advancer - checking initialisation queue. Queue size 1.
17:52:59.345 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208  ] - NODE 4: Node advancer - message removed from queue. Queue size 0.
17:52:59.346 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267  ] - NODE 4: Node advancer - FAILED_CHECK: queue length(0), free to send(true)
17:52:59.347 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - FAILED_CHECK try 1: stageAdvanced(false)
17:52:59.347 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890  ] - NODE 4: Node advancer - advancing to WAIT
17:52:59.348 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332  ] - NODE 4: Node advancer: loop - WAIT try 0: stageAdvanced(true)
17:52:59.349 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:380  ] - NODE 4: Node advancer: WAIT - Listening=false, FrequentlyListening=false
17:52:59.349 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:398  ] - NODE 4: Node advancer: WAIT - Still waiting!
17:52:59.367 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1020 ] - NODE 4: Node advancer - WAIT: The WAIT is over!
17:52:59.367 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202  ] - NODE 4: Node advancer - checking initialisation queue. Queue size 0.
17:53:05.151 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Temp is not completed initialisation
17:53:05.152 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Alarm is not completed initialisation
17:53:05.153 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Bat is not completed initialisation
17:53:05.155 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Lux is not completed initialisation
17:53:05.156 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Movement is not completed initialisation
17:53:16.687 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Temp is not completed initialisation
17:53:16.687 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Alarm is not completed initialisation
17:53:16.689 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Bat is not completed initialisation
17:53:16.690 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Lux is not completed initialisation
17:53:16.691 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 4: Polling list: item sensor1_Movement is not completed initialisation
17:53:41.508 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (FAILED:PING)
17:53:41.510 [DEBUG] [.z.internal.protocol.ZWaveNode:244  ] - NODE 4: Node has risen from the DEAD. Init stage is PING:{}.
17:53:41.512 [DEBUG] [.z.internal.protocol.ZWaveNode:269  ] - NODE 4: Initialisation incomplete, not signalling state change.
17:53:41.514 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:53:41.515 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:53:41.517 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:53:41.518 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:53:41.521 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:53:41.561 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:41.561 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:53:41.561 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:53:41.561 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:53:41.561 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:53:41.561 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:53:41.586 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:41.587 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:53:41.588 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:53:41.588 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:53:41.589 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:53:41.591 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:53:41.987 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:41.987 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:53:41.987 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:53:41.987 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:53:41.987 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:53:42.003 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:53:42.096 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:42.096 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:53:42.096 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:53:42.096 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:53:42.096 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0xFF
17:53:42.096 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
17:53:42.112 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:42.112 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:53:42.112 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:53:42.112 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:53:42.112 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0xFF
17:53:42.112 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
17:53:42.159 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:42.159 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:53:42.159 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:53:42.164 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:53:42.164 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0xFF
17:53:42.164 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
17:53:45.325 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:53:45.325 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class SENSOR_MULTILEVEL
17:53:45.326 [DEBUG] [veMultiLevelSensorCommandClass:95   ] - NODE 4: Received Sensor Multi Level Request
17:53:45.326 [DEBUG] [veMultiLevelSensorCommandClass:130  ] - NODE 4: Sensor Multi Level REPORT received
17:53:45.326 [DEBUG] [veMultiLevelSensorCommandClass:145  ] - NODE 4: Sensor Type = Luminance(3), Scale = 1
17:53:45.327 [DEBUG] [veMultiLevelSensorCommandClass:154  ] - NODE 4: Sensor Value = 2
17:53:45.328 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 2
17:54:12.377 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:54:12.377 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:54:12.377 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:54:12.392 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:54:12.392 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:54:12.392 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:54:12.489 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:54:12.489 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:54:12.489 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:54:12.489 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:54:12.489 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0x00
17:54:12.489 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
17:54:12.505 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:54:12.505 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:54:12.505 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:54:12.505 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:54:12.505 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0x00
17:54:12.505 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
17:54:12.677 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:54:12.677 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:54:12.677 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:54:12.677 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:54:12.677 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:54:12.677 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:54:12.755 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:54:12.755 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:54:12.755 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:54:12.755 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:54:12.755 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:54:12.755 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:54:12.771 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:54:12.771 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:54:12.771 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:54:12.771 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:54:12.771 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:54:12.786 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:57:28.740 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:57:28.740 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class SENSOR_MULTILEVEL
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:95   ] - NODE 4: Received Sensor Multi Level Request
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:130  ] - NODE 4: Sensor Multi Level REPORT received
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:145  ] - NODE 4: Sensor Type = Temperature(1), Scale = 0
17:57:28.740 [DEBUG] [veMultiLevelSensorCommandClass:154  ] - NODE 4: Sensor Value = 24.4
17:57:28.740 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 24.4
17:58:06.090 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.090 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:06.090 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:06.090 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:06.090 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:06.105 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:06.163 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.163 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:06.163 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:06.163 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:06.163 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:06.163 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:06.179 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.179 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:06.179 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:06.179 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:06.179 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:06.188 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:06.391 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.391 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:06.391 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:06.391 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:06.391 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:06.407 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:06.493 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.493 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:58:06.493 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:58:06.493 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:58:06.493 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0xFF
17:58:06.493 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
17:58:06.508 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:06.508 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:58:06.508 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:58:06.508 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:58:06.508 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0xFF
17:58:06.524 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
17:58:36.838 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:36.838 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:36.838 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:36.838 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:36.838 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:36.854 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:36.943 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:36.943 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:58:36.943 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:58:36.943 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:58:36.943 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0x00
17:58:36.943 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
17:58:36.958 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:36.958 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class BASIC
17:58:36.958 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:73   ] - NODE 4: Received Basic Request
17:58:36.958 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:77   ] - NODE 4: Basic Set sent to the controller will be processed as Basic Report
17:58:36.958 [DEBUG] [z.i.p.c.ZWaveBasicCommandClass:106  ] - NODE 4: Basic report, value = 0x00
17:58:36.974 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
17:58:37.148 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:37.148 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:37.148 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:37.148 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:37.148 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:37.148 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:37.217 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:37.219 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:37.220 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:37.221 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:37.222 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:37.228 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
17:58:37.235 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 4: Application Command Request (ALIVE:PING)
17:58:37.250 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 4: Incoming command class ALARM
17:58:37.250 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 4: Received Alarm Request
17:58:37.250 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 4: Alarm report - Value = 0
17:58:37.250 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 4: Alarm Type = General (0)
17:58:37.250 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0

Zvika · August 15, 2016, 11:44am

And here is a raw log (unfortunately, not ‘debug’ - but this is what I have now) of using “BC” groups:

2016-08-15 09:39:31.875 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:39:32.097 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:39:32.316 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 32, ignoring.
2016-08-15 09:39:32.479 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 29.7, ignoring.
2016-08-15 09:39:37.179 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:39:37.457 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:39:40.335 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class ZWAVE_PLUS_INFO
2016-08-15 09:39:40.337 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class DEVICE_RESET_LOCALLY
2016-08-15 09:39:40.338 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class ASSOCIATION_GROUP_INFO
2016-08-15 09:39:40.340 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class POWERLEVEL
2016-08-15 09:39:40.343 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class MULTI_INSTANCE_ASSOCIATION
2016-08-15 09:39:40.346 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class SECURITY
2016-08-15 09:39:40.347 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass] - NODE 5: Unsupported command class FIRMWARE_UPDATE_MD
2016-08-15 09:39:40.376 [WARN ] [b.z.i.p.s.SendDataMessageClass] - NODE 5: Already processed another send data request for this callback Id, ignoring.
2016-08-15 09:39:45.353 [ERROR] [WaveController$ZWaveSendThread] - NODE 5: Timeout while sending message. Requeueing - 0 attempts left!
2016-08-15 09:39:53.444 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:40:11.972 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@a21797, ignoring.
2016-08-15 09:40:12.012 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1076319, ignoring.
2016-08-15 09:40:12.054 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@d70123, ignoring.
2016-08-15 09:40:12.095 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1d0ab99, ignoring.
2016-08-15 09:40:12.136 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@a0d60b, ignoring.
2016-08-15 09:40:12.175 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1f8485e, ignoring.
2016-08-15 09:40:12.216 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1614655, ignoring.
2016-08-15 09:40:12.257 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@585306, ignoring.
2016-08-15 09:40:12.296 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@5fff58, ignoring.
2016-08-15 09:40:12.336 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@199c910, ignoring.
2016-08-15 09:40:12.379 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1b581d9, ignoring.
2016-08-15 09:40:12.419 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@74e7b7, ignoring.
2016-08-15 09:40:12.458 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1d8855f, ignoring.
2016-08-15 09:40:12.504 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@ec3881, ignoring.
2016-08-15 09:40:12.548 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@ec3651, ignoring.
2016-08-15 09:40:12.592 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@101b097, ignoring.
2016-08-15 09:40:12.638 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@273342, ignoring.
2016-08-15 09:40:12.683 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1f643ed, ignoring.
2016-08-15 09:40:12.727 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1031013, ignoring.
2016-08-15 09:40:12.773 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1330a6a, ignoring.
2016-08-15 09:40:12.818 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@ab2e5a, ignoring.
2016-08-15 09:40:12.863 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@166251b, ignoring.
2016-08-15 09:40:12.908 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@f666fc, ignoring.
2016-08-15 09:40:12.954 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@ec1142, ignoring.
2016-08-15 09:40:12.997 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@4880f, ignoring.
2016-08-15 09:40:13.044 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@1b63600, ignoring.
2016-08-15 09:40:13.092 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@ad0592, ignoring.
2016-08-15 09:40:13.139 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@15d1164, ignoring.
2016-08-15 09:40:13.186 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@2d3149, ignoring.
2016-08-15 09:40:13.232 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@138c689, ignoring.
2016-08-15 09:40:13.346 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.385 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.425 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.464 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = BATTERY, value = 100, ignoring.
2016-08-15 09:40:13.504 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 0, ignoring.
2016-08-15 09:40:13.544 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:40:13.584 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 0, ignoring.
2016-08-15 09:40:13.624 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.664 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.704 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.745 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.785 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.824 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.862 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.902 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.940 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.980 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9, ignoring.
2016-08-15 09:40:13.981 [ERROR] [z.i.p.i.ZWaveNodeStageAdvancer] - NODE 5: Node advancer: Retries exceeded at DYNAMIC_VALUES
2016-08-15 09:40:15.853 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:40:16.080 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:40:16.298 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 1E+1, ignoring.
2016-08-15 09:40:16.458 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 29.9, ignoring.
2016-08-15 09:40:28.448 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:41:03.454 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:41:08.996 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:41:09.515 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:41:38.461 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:41:39.414 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:41:39.691 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:41:53.069 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:41:53.338 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:42:13.468 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:42:48.474 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:42:49.744 [ERROR] [WaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2016-08-15 09:42:50.605 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:42:50.710 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
2016-08-15 09:42:50.726 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
2016-08-15 09:42:50.903 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 65, ignoring.
2016-08-15 09:42:51.069 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 29.9, ignoring.
2016-08-15 09:42:54.774 [ERROR] [WaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2016-08-15 09:42:55.038 [ERROR] [.DeleteReturnRouteMessageClass] - NODE 5: Delete return routes failed with error 0x1.
2016-08-15 09:42:59.777 [ERROR] [WaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2016-08-15 09:42:59.934 [ERROR] [.DeleteReturnRouteMessageClass] - NODE 5: Delete return routes failed with error 0x1.
2016-08-15 09:43:04.780 [WARN ] [WaveController$ZWaveSendThread] - NODE 255: Too many retries. Discarding message: Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 05 
2016-08-15 09:43:04.912 [ERROR] [.AssignReturnRouteMessageClass] - NODE 5: Assign return routes failed.
2016-08-15 09:43:15.229 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:43:15.357 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 0, ignoring.
2016-08-15 09:43:15.398 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 0, ignoring.
2016-08-15 09:43:23.481 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:43:35.973 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:43:36.103 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
2016-08-15 09:43:36.144 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
2016-08-15 09:43:39.169 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 55, ignoring.
2016-08-15 09:43:58.488 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:44:05.105 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:44:05.186 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 0, ignoring.
2016-08-15 09:44:05.224 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 0, ignoring.
2016-08-15 09:44:06.110 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:44:06.235 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 0, ignoring.
2016-08-15 09:44:06.274 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 0, ignoring.
2016-08-15 09:44:15.675 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:44:15.757 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 255, ignoring.
2016-08-15 09:44:15.795 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 255, ignoring.
2016-08-15 09:44:33.495 [WARN ] [inding.ntp.internal.NtpBinding] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
2016-08-15 09:44:41.529 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
2016-08-15 09:44:41.656 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
2016-08-15 09:44:41.696 [WARN ] [.z.internal.ZWaveActiveBinding] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.

chris · August 15, 2016, 11:51am

Sorry, but neither of these logs are of any use :(. Please don’t grep the logs - the filtering removes the information that I need to see, and logs without debug are of very limited use for debugging

Zvika · August 15, 2016, 11:52am

Sorry, this is what I saved before leaving home.
Will post something better tonight, or tomorrow.

Thanks again for the great help!

chris · August 15, 2016, 11:53am

Ok - no problem. Just send them over when you get a chance and I’ll take a look.

Zvika · August 15, 2016, 3:48pm

Here is log file with ‘BC’ groups:

18:35:00.187 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:00.187 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:35:00.187 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:35:00.187 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:35:00.187 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:35:00.187 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:35:00.187 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:00.187 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:35:00.187 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:35:00.187 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:00.250 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 08 05 03 30 03 FF 30 
18:35:00.266 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:00.266 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:00.266 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 08 05 03 30 03 FF 30 
18:35:00.266 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 08 05 03 30 03 FF 30 
18:35:00.266 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 03 30 03 FF 
18:35:00.266 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:00.266 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:35:00.266 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:35:00.266 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=255
18:35:00.266 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:35:00.266 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:00.266 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
18:35:00.266 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 255, ignoring.
18:35:00.266 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:00.297 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 00 05 03 30 03 FF 38 
18:35:00.297 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:00.297 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:00.297 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 00 05 03 30 03 FF 38 
18:35:00.297 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 00 05 03 30 03 FF 38 
18:35:00.297 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 03 30 03 FF 
18:35:00.297 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:00.297 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:35:00.313 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:35:00.313 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=255
18:35:00.313 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:35:00.313 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:00.313 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
18:35:00.313 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 255, ignoring.
18:35:00.313 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:26.948 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.103' port '0' timeout '5000']
18:35:30.347 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:35:30.350 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:30.350 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:30.351 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:35:30.351 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:35:30.351 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 08 
18:35:30.351 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:30.351 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:35:30.351 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:35:30.351 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:35:30.351 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:35:30.351 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:35:30.351 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:30.351 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:35:30.351 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:35:30.367 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:30.429 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 08 05 03 30 03 00 CF 
18:35:30.429 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:30.429 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:30.429 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 08 05 03 30 03 00 CF 
18:35:30.429 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 08 05 03 30 03 00 CF 
18:35:30.429 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 03 30 03 00 
18:35:30.429 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:30.429 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:35:30.429 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:35:30.429 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=0
18:35:30.445 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:35:30.445 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:30.445 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 0
18:35:30.445 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 0, ignoring.
18:35:30.445 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:30.461 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 00 05 03 30 03 00 C7 
18:35:30.461 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:30.461 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:30.461 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 00 05 03 30 03 00 C7 
18:35:30.461 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 00 05 03 30 03 00 C7 
18:35:30.476 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 03 30 03 00 
18:35:30.476 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:30.476 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:35:30.476 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:35:30.476 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=0
18:35:30.476 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:35:30.476 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:30.476 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 0
18:35:30.476 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 0, ignoring.
18:35:30.476 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:31.696 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.105' port '0' timeout '5000']
18:35:31.696 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.103' port '0' timeout '5000']
18:35:31.805 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host 'openhab.org' port '80' timeout '5000']
18:35:34.833 [WARN ] [inding.ntp.internal.NtpBinding:127  ] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
18:35:34.833 [DEBUG] [inding.ntp.internal.NtpBinding:84   ] - Got time from ptbtime1.ptb.de: 18:35:34 IDT יום שני 15 אוגוסט 2016
18:35:34.833 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(Date), state = 2016-08-15T18:35:34
18:35:41.108 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 08 00 7C 
18:35:41.108 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:41.108 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:41.108 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 08 00 7C 
18:35:41.108 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 08 00 7C 
18:35:41.108 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 09 71 05 00 00 00 FF 07 08 00 
18:35:41.108 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:41.108 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:35:41.108 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:35:41.108 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:35:41.108 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:35:41.124 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:35:41.124 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:41.124 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:35:41.124 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:35:41.124 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:41.186 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 08 05 03 30 03 FF 30 
18:35:41.186 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:41.186 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:41.186 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 08 05 03 30 03 FF 30 
18:35:41.186 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 08 05 03 30 03 FF 30 
18:35:41.186 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 03 30 03 FF 
18:35:41.186 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:41.202 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:35:41.202 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:35:41.202 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=255
18:35:41.202 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:35:41.202 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:41.202 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
18:35:41.202 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 255, ignoring.
18:35:41.202 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:35:41.218 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 00 05 03 30 03 FF 38 
18:35:41.233 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:35:41.233 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:35:41.233 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 00 05 03 30 03 FF 38 
18:35:41.233 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 00 05 03 30 03 FF 38 
18:35:41.233 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 03 30 03 FF 
18:35:41.233 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:35:41.233 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:35:41.233 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:35:41.233 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=255
18:35:41.233 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:35:41.233 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:35:41.233 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
18:35:41.233 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 255, ignoring.
18:35:41.250 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:36:00.020 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53   ] - Executing scheduled rule 'check_temp'
18:36:00.036 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(auto_AC_check_time), state = 18:36:0
18:36:00.153 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
18:36:00.168 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:00.168 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:00.168 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
18:36:00.168 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
18:36:00.168 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 09 71 05 00 00 00 FF 07 03 00 
18:36:00.168 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:36:00.168 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:36:00.168 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:36:00.168 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:36:00.168 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:36:00.168 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:36:00.168 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:36:00.168 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:36:00.168 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:36:00.168 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:36:00.293 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0D 00 04 08 05 07 9C 02 05 00 FF 00 00 98 
18:36:00.293 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:00.293 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:00.293 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0D 00 04 08 05 07 9C 02 05 00 FF 00 00 98 
18:36:00.293 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0D 00 04 08 05 07 9C 02 05 00 FF 00 00 98 
18:36:00.293 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 07 9C 02 05 00 FF 00 00 
18:36:00.293 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:36:00.293 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_ALARM
18:36:00.293 [DEBUG] [c.ZWaveAlarmSensorCommandClass:85   ] - NODE 5: Received Sensor Alarm Request
18:36:00.293 [DEBUG] [c.ZWaveAlarmSensorCommandClass:104  ] - NODE 5: Alarm Report: Source=5, Type=General(0), Value=255
18:36:00.293 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmSensorValueEvent
18:36:00.309 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:36:00.309 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_ALARM, value = 255
18:36:00.309 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
18:36:00.309 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:36:00.325 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0D 00 04 00 05 07 9C 02 05 00 FF 00 00 90 
18:36:00.325 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:00.325 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:00.340 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0D 00 04 00 05 07 9C 02 05 00 FF 00 00 90 
18:36:00.340 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0D 00 04 00 05 07 9C 02 05 00 FF 00 00 90 
18:36:00.340 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 07 9C 02 05 00 FF 00 00 
18:36:00.340 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:36:00.340 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_ALARM
18:36:00.340 [DEBUG] [c.ZWaveAlarmSensorCommandClass:85   ] - NODE 5: Received Sensor Alarm Request
18:36:00.340 [DEBUG] [c.ZWaveAlarmSensorCommandClass:104  ] - NODE 5: Alarm Report: Source=5, Type=General(0), Value=255
18:36:00.340 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmSensorValueEvent
18:36:00.340 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:36:00.340 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_ALARM, value = 255
18:36:00.340 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_ALARM, value = 255, ignoring.
18:36:00.356 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:36:09.860 [WARN ] [inding.ntp.internal.NtpBinding:127  ] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
18:36:09.860 [DEBUG] [inding.ntp.internal.NtpBinding:84   ] - Got time from ptbtime1.ptb.de: 18:36:09 IDT יום שני 15 אוגוסט 2016
18:36:09.860 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(Date), state = 2016-08-15T18:36:09
18:36:15.208 [DEBUG] [z.internal.ZWaveNetworkMonitor:351  ] - NODE 2: Sending periodic PING.
18:36:15.208 [DEBUG] [c.ZWaveNoOperationCommandClass:72   ] - NODE 2: Creating new message for command No Operation
18:36:15.208 [DEBUG] [o.b.z.i.protocol.SerialMessage:113  ] - NODE 2: Creating empty message of class = SendData (0x13), type = Request (0x00)
18:36:15.208 [DEBUG] [b.z.i.protocol.ZWaveController:947  ] - Callback ID = 21
18:36:15.208 [DEBUG] [b.z.i.protocol.ZWaveController:632  ] - Enqueueing message. Queue length = 1
18:36:15.208 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 0
18:36:15.208 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 08 00 13 02 01 00 25 15 D7 
18:36:15.208 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 2: Sending REQUEST Message = 01 08 00 13 02 01 00 25 15 D7 
18:36:15.223 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 13 01 E8 
18:36:15.223 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:15.223 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:15.223 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 13 01 E8 
18:36:15.223 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 13 01 E8 
18:36:15.223 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:36:15.223 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38   ] - NODE 2: Sent Data successfully placed on stack.
18:36:15.239 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 07 00 13 15 00 00 02 FC 
18:36:15.239 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:15.239 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:15.239 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 13 15 00 00 02 00 00 F2 
18:36:15.239 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 13 15 00 00 02 00 00 F2 
18:36:15.239 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = SendData (0x13), type = Request (0x00), payload = 15 00 00 02 
18:36:15.239 [DEBUG] [b.z.i.p.s.SendDataMessageClass:73   ] - NODE 2: SendData Request. CallBack ID = 21, Status = Transmission complete and ACK received(0)
18:36:15.239 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66   ] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 01 00 
18:36:15.255 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67   ] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 15 00 00 02 
18:36:15.255 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68   ] - Checking transaction complete: class=SendData, expected=SendData, cancelled=false
18:36:15.255 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74   ] -          transaction complete!
18:36:15.255 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:36:15.255 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:36:15.255 [DEBUG] [WaveController$ZWaveSendThread:1364 ] - NODE 2: Response processed after 47ms/4304ms.
18:36:30.350 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:36:30.350 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:30.350 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:30.350 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:36:30.350 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:36:30.350 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 08 
18:36:30.350 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:36:30.350 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:36:30.350 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:36:30.365 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:36:30.365 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:36:30.365 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:36:30.365 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:36:30.365 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:36:30.365 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:36:30.365 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:36:30.428 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 09 00 04 08 05 03 30 03 00 CF 
18:36:30.428 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:36:30.428 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:36:30.428 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 09 00 04 08 05 03 30 03 00 CF 
18:36:30.428 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 09 00 04 08 05 03 30 03 00 CF 
18:36:30.444 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 03 30 03 00 
18:36:30.444 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:PING)
18:36:30.444 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_BINARY
18:36:30.444 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 5: Received Sensor Binary Request (v1)
18:36:30.444 [DEBUG] [.ZWaveBinarySensorCommandClass:103  ] - NODE 5: Sensor Binary report, type=Unknown, value=0
18:36:30.444 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveBinarySensorValueEvent
18:36:30.444 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:36:30.444 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 0
18:36:30.444 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_BINARY, value = 0, ignoring.
18:36:30.444 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.

Zvika · August 15, 2016, 3:55pm

And here, I’ve removed the 2 BC groups, leaving only ‘Fifeline’.
Pay attention to the weird ‘NODE 255’ errors, and the NODE 5: Delete return routes failed with error 0x1 errors.

18:52:21.449 [ERROR] [WaveController$ZWaveSendThread:1347 ] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
18:52:21.449 [DEBUG] [b.z.i.protocol.ZWaveController:632  ] - Enqueueing message. Queue length = 2
18:52:21.449 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 1
18:52:21.449 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 00 47 05 B9 
18:52:21.449 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 255: Sending REQUEST Message = 01 04 00 47 05 B9 
18:52:21.449 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 47 01 BC 
18:52:21.449 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:21.449 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:21.449 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 47 01 BC 
18:52:21.449 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 47 01 BC 
18:52:21.449 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Response (0x01), payload = 01 
18:52:21.464 [DEBUG] [.DeleteReturnRouteMessageClass:46   ] - NODE 5: Got DeleteReturnRoute response.
18:52:21.464 [DEBUG] [.DeleteReturnRouteMessageClass:48   ] - NODE 5: DeleteReturnRoute command in progress.
18:52:21.689 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 05 00 47 02 01 BE 
18:52:21.689 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:21.689 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:21.689 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 05 00 47 02 01 BE 
18:52:21.704 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 05 00 47 02 01 BE 
18:52:21.704 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 02 01 
18:52:21.704 [DEBUG] [.DeleteReturnRouteMessageClass:63   ] - NODE 5: Got DeleteReturnRoute request.
18:52:21.704 [ERROR] [.DeleteReturnRouteMessageClass:65   ] - NODE 5: Delete return routes failed with error 0x1.
18:52:21.704 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveNetworkEvent
18:52:21.704 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:52:26.465 [ERROR] [WaveController$ZWaveSendThread:1347 ] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
18:52:26.465 [DEBUG] [b.z.i.protocol.ZWaveController:632  ] - Enqueueing message. Queue length = 2
18:52:26.465 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 1
18:52:26.465 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 00 47 05 B9 
18:52:26.465 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 255: Sending REQUEST Message = 01 04 00 47 05 B9 
18:52:26.465 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 47 01 BC 
18:52:26.465 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:26.465 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:26.465 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 47 01 BC 
18:52:26.465 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 47 01 BC 
18:52:26.465 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Response (0x01), payload = 01 
18:52:26.465 [DEBUG] [.DeleteReturnRouteMessageClass:46   ] - NODE 5: Got DeleteReturnRoute response.
18:52:26.465 [DEBUG] [.DeleteReturnRouteMessageClass:48   ] - NODE 5: DeleteReturnRoute command in progress.
18:52:26.575 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 05 00 47 02 01 BE 
18:52:26.575 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:26.575 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:26.575 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 05 00 47 02 01 BE 
18:52:26.575 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 05 00 47 02 01 BE 
18:52:26.575 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 02 01 
18:52:26.575 [DEBUG] [.DeleteReturnRouteMessageClass:63   ] - NODE 5: Got DeleteReturnRoute request.
18:52:26.575 [ERROR] [.DeleteReturnRouteMessageClass:65   ] - NODE 5: Delete return routes failed with error 0x1.
18:52:26.575 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveNetworkEvent
18:52:26.575 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:52:30.535 [WARN ] [inding.ntp.internal.NtpBinding:127  ] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
18:52:30.535 [DEBUG] [inding.ntp.internal.NtpBinding:84   ] - Got time from ptbtime1.ptb.de: 18:52:30 IDT יום שני 15 אוגוסט 2016
18:52:30.535 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(Date), state = 2016-08-15T18:52:30
18:52:31.475 [WARN ] [WaveController$ZWaveSendThread:1355 ] - NODE 255: Too many retries. Discarding message: Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 05 
18:52:31.475 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 0
18:52:31.475 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 06 00 46 05 01 47 FC 
18:52:31.475 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 255: Sending REQUEST Message = 01 06 00 46 05 01 47 FC 
18:52:31.475 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 46 01 BD 
18:52:31.491 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:31.491 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:31.491 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 46 01 BD 
18:52:31.491 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 46 01 BD 
18:52:31.491 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = AssignReturnRoute (0x46), type = Response (0x01), payload = 01 
18:52:31.491 [DEBUG] [.AssignReturnRouteMessageClass:45   ] - NODE 5: Got AssignReturnRoute response.
18:52:31.491 [DEBUG] [.AssignReturnRouteMessageClass:48   ] - NODE 5: AssignReturnRoute command in progress.
18:52:31.616 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 05 00 46 47 01 FA 
18:52:31.616 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:31.616 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:31.616 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 05 00 46 47 01 FA 
18:52:31.616 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 05 00 46 47 01 FA 
18:52:31.616 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = AssignReturnRoute (0x46), type = Request (0x00), payload = 47 01 
18:52:31.616 [DEBUG] [.AssignReturnRouteMessageClass:64   ] - NODE 5: Got AssignReturnRoute request.
18:52:31.616 [ERROR] [.AssignReturnRouteMessageClass:66   ] - NODE 5: Assign return routes failed.
18:52:31.631 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveNetworkEvent
18:52:31.631 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:52:31.631 [DEBUG] [z.internal.ZWaveNetworkMonitor:655  ] - NODE 5: Network heal received FAILURE event
18:52:31.631 [DEBUG] [z.internal.ZWaveNetworkMonitor:441  ] - NODE 5: Node is asleep. Defer heal until it's awake
18:52:31.631 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66   ] - Sent message Message: class = AssignReturnRoute (0x46), type = Request (0x00), payload = 05 01 47 
18:52:31.631 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67   ] - Recv message Message: class = AssignReturnRoute (0x46), type = Request (0x00), payload = 47 01 
18:52:31.631 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68   ] - Checking transaction complete: class=AssignReturnRoute, expected=AssignReturnRoute, cancelled=false
18:52:31.631 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74   ] -          transaction complete!
18:52:31.631 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:52:31.631 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:52:31.631 [DEBUG] [WaveController$ZWaveSendThread:1364 ] - NODE 255: Response processed after 156ms/4312ms.
18:52:41.655 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
18:52:41.655 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:41.655 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:41.655 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
18:52:41.655 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
18:52:41.655 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 03 
18:52:41.655 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
18:52:41.655 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:52:41.655 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:52:41.655 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:52:41.655 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:52:41.655 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:52:41.655 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:52:41.655 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:52:41.655 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:52:41.655 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:52:44.457 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
18:52:44.457 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:52:44.457 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:52:44.457 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
18:52:44.457 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
18:52:44.457 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 09 71 05 00 00 00 FF 07 03 00 
18:52:44.457 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
18:52:44.457 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:52:44.457 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:52:44.457 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:52:44.457 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:52:44.457 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:52:44.457 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:52:44.457 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:52:44.457 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:52:44.457 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:52:47.093 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.103' port '0' timeout '5000']
18:52:51.981 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.105' port '0' timeout '5000']
18:52:51.981 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.103' port '0' timeout '5000']
18:52:52.081 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host 'openhab.org' port '80' timeout '5000']
18:53:00.011 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53   ] - Executing scheduled rule 'check_temp'
18:53:00.026 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(auto_AC_check_time), state = 18:53:0
18:53:05.544 [WARN ] [inding.ntp.internal.NtpBinding:127  ] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
18:53:05.544 [DEBUG] [inding.ntp.internal.NtpBinding:84   ] - Got time from ptbtime1.ptb.de: 18:53:05 IDT יום שני 15 אוגוסט 2016
18:53:05.544 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(Date), state = 2016-08-15T18:53:05
18:53:13.663 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:53:13.663 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:53:13.663 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:53:13.663 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:53:13.663 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 08 61 
18:53:13.663 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 08 
18:53:13.679 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
18:53:13.679 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:53:13.679 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:53:13.679 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:53:13.679 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:53:13.679 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:53:13.679 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:53:13.679 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:53:13.679 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:53:13.679 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
18:53:14.680 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
18:53:14.680 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
18:53:14.680 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
18:53:14.680 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
18:53:14.680 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
18:53:14.680 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 03 
18:53:14.680 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
18:53:14.680 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
18:53:14.680 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
18:53:14.680 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
18:53:14.680 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
18:53:14.695 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
18:53:14.695 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
18:53:14.695 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
18:53:14.695 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
18:53:14.695 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.

Zvika · August 15, 2016, 4:06pm

And here is with ‘Regular’ groups, without ‘BC’:

19:02:32.176 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:02:32.192 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:02:32.192 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:02:32.192 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:02:32.192 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:02:32.192 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:32.192 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:02:32.192 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:02:32.192 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:02:32.344 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0C 00 04 00 05 06 31 05 03 0A 00 4F 86 
19:02:32.344 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:32.344 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:32.344 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0C 00 04 00 05 06 31 05 03 0A 00 4F 86 
19:02:32.344 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0C 00 04 00 05 06 31 05 03 0A 00 4F 86 
19:02:32.344 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 06 31 05 03 0A 00 4F 
19:02:32.344 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:02:32.344 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_MULTILEVEL
19:02:32.359 [DEBUG] [veMultiLevelSensorCommandClass:95   ] - NODE 5: Received Sensor Multi Level Request
19:02:32.359 [DEBUG] [veMultiLevelSensorCommandClass:130  ] - NODE 5: Sensor Multi Level REPORT received
19:02:32.359 [DEBUG] [veMultiLevelSensorCommandClass:145  ] - NODE 5: Sensor Type = Luminance(3), Scale = 1
19:02:32.359 [DEBUG] [veMultiLevelSensorCommandClass:154  ] - NODE 5: Sensor Value = 79
19:02:32.359 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
19:02:32.359 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:32.359 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 79
19:02:32.359 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 79, ignoring.
19:02:32.359 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:02:32.500 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0C 00 04 00 05 06 31 05 01 22 01 03 E1 
19:02:32.500 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:32.516 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:32.516 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0C 00 04 00 05 06 31 05 01 22 01 03 E1 
19:02:32.516 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0C 00 04 00 05 06 31 05 01 22 01 03 E1 
19:02:32.516 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 06 31 05 01 22 01 03 
19:02:32.516 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:02:32.516 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class SENSOR_MULTILEVEL
19:02:32.516 [DEBUG] [veMultiLevelSensorCommandClass:95   ] - NODE 5: Received Sensor Multi Level Request
19:02:32.516 [DEBUG] [veMultiLevelSensorCommandClass:130  ] - NODE 5: Sensor Multi Level REPORT received
19:02:32.516 [DEBUG] [veMultiLevelSensorCommandClass:145  ] - NODE 5: Sensor Type = Temperature(1), Scale = 0
19:02:32.516 [DEBUG] [veMultiLevelSensorCommandClass:154  ] - NODE 5: Sensor Value = 25.9
19:02:32.516 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
19:02:32.531 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:32.531 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25.9
19:02:32.531 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25.9, ignoring.
19:02:32.531 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:02:35.946 [ERROR] [WaveController$ZWaveSendThread:1347 ] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
19:02:35.947 [DEBUG] [b.z.i.protocol.ZWaveController:632  ] - Enqueueing message. Queue length = 2
19:02:35.947 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 1
19:02:35.947 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 00 47 05 B9 
19:02:35.947 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 255: Sending REQUEST Message = 01 04 00 47 05 B9 
19:02:35.947 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 47 01 BC 
19:02:35.962 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:35.962 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:35.962 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 47 01 BC 
19:02:35.962 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 47 01 BC 
19:02:35.962 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Response (0x01), payload = 01 
19:02:35.962 [DEBUG] [.DeleteReturnRouteMessageClass:46   ] - NODE 5: Got DeleteReturnRoute response.
19:02:35.962 [DEBUG] [.DeleteReturnRouteMessageClass:48   ] - NODE 5: DeleteReturnRoute command in progress.
19:02:36.199 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 05 00 47 02 01 BE 
19:02:36.199 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:36.199 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:36.199 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 05 00 47 02 01 BE 
19:02:36.199 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 05 00 47 02 01 BE 
19:02:36.199 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 02 01 
19:02:36.199 [DEBUG] [.DeleteReturnRouteMessageClass:63   ] - NODE 5: Got DeleteReturnRoute request.
19:02:36.199 [ERROR] [.DeleteReturnRouteMessageClass:65   ] - NODE 5: Delete return routes failed with error 0x1.
19:02:36.199 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveNetworkEvent
19:02:36.199 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:36.984 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.105' port '0' timeout '5000']
19:02:36.984 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host '192.168.1.103' port '0' timeout '5000']
19:02:37.062 [DEBUG] [o.o.b.n.i.NetworkHealthBinding:85   ] - established connection [host 'openhab.org' port '80' timeout '5000']
19:02:40.956 [ERROR] [WaveController$ZWaveSendThread:1347 ] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
19:02:40.956 [DEBUG] [b.z.i.protocol.ZWaveController:632  ] - Enqueueing message. Queue length = 2
19:02:40.956 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 1
19:02:40.956 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 00 47 05 B9 
19:02:40.956 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 255: Sending REQUEST Message = 01 04 00 47 05 B9 
19:02:40.972 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 47 01 BC 
19:02:40.972 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:40.972 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:40.972 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 47 01 BC 
19:02:40.972 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 47 01 BC 
19:02:40.972 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Response (0x01), payload = 01 
19:02:40.972 [DEBUG] [.DeleteReturnRouteMessageClass:46   ] - NODE 5: Got DeleteReturnRoute response.
19:02:40.972 [DEBUG] [.DeleteReturnRouteMessageClass:48   ] - NODE 5: DeleteReturnRoute command in progress.
19:02:41.066 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 05 00 47 02 01 BE 
19:02:41.066 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:41.066 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:41.066 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 05 00 47 02 01 BE 
19:02:41.081 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 05 00 47 02 01 BE 
19:02:41.081 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 02 01 
19:02:41.081 [DEBUG] [.DeleteReturnRouteMessageClass:63   ] - NODE 5: Got DeleteReturnRoute request.
19:02:41.081 [ERROR] [.DeleteReturnRouteMessageClass:65   ] - NODE 5: Delete return routes failed with error 0x1.
19:02:41.081 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveNetworkEvent
19:02:41.081 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:45.966 [WARN ] [WaveController$ZWaveSendThread:1355 ] - NODE 255: Too many retries. Discarding message: Message: class = DeleteReturnRoute (0x47), type = Request (0x00), payload = 05 
19:02:45.966 [DEBUG] [WaveController$ZWaveSendThread:1241 ] - Took message from queue for sending. Queue length = 0
19:02:45.966 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 06 00 46 05 01 61 DA 
19:02:45.966 [DEBUG] [WaveController$ZWaveSendThread:1301 ] - NODE 255: Sending REQUEST Message = 01 06 00 46 05 01 61 DA 
19:02:45.981 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 04 01 46 01 BD 
19:02:45.981 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:45.981 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:45.981 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 04 01 46 01 BD 
19:02:45.981 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 04 01 46 01 BD 
19:02:45.981 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = AssignReturnRoute (0x46), type = Response (0x01), payload = 01 
19:02:45.981 [DEBUG] [.AssignReturnRouteMessageClass:45   ] - NODE 5: Got AssignReturnRoute response.
19:02:45.981 [DEBUG] [.AssignReturnRouteMessageClass:48   ] - NODE 5: AssignReturnRoute command in progress.
19:02:46.106 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 05 00 46 61 01 DC 
19:02:46.106 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:46.106 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:46.106 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 05 00 46 61 01 DC 
19:02:46.122 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 05 00 46 61 01 DC 
19:02:46.122 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = AssignReturnRoute (0x46), type = Request (0x00), payload = 61 01 
19:02:46.122 [DEBUG] [.AssignReturnRouteMessageClass:64   ] - NODE 5: Got AssignReturnRoute request.
19:02:46.122 [ERROR] [.AssignReturnRouteMessageClass:66   ] - NODE 5: Assign return routes failed.
19:02:46.122 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveNetworkEvent
19:02:46.122 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:46.122 [DEBUG] [z.internal.ZWaveNetworkMonitor:655  ] - NODE 5: Network heal received FAILURE event
19:02:46.122 [DEBUG] [z.internal.ZWaveNetworkMonitor:441  ] - NODE 5: Node is asleep. Defer heal until it's awake
19:02:46.122 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:66   ] - Sent message Message: class = AssignReturnRoute (0x46), type = Request (0x00), payload = 05 01 61 
19:02:46.122 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:67   ] - Recv message Message: class = AssignReturnRoute (0x46), type = Request (0x00), payload = 61 01 
19:02:46.122 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68   ] - Checking transaction complete: class=AssignReturnRoute, expected=AssignReturnRoute, cancelled=false
19:02:46.138 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:74   ] -          transaction complete!
19:02:46.138 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveTransactionCompletedEvent
19:02:46.138 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:46.138 [DEBUG] [WaveController$ZWaveSendThread:1364 ] - NODE 255: Response processed after 172ms/4312ms.
19:02:55.984 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:02:55.984 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:55.984 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:56.000 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:02:56.000 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:02:56.000 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 03 
19:02:56.000 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:02:56.000 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:02:56.000 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:02:56.000 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:02:56.000 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:02:56.000 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:02:56.000 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:56.016 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:02:56.016 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:02:56.016 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:02:56.078 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 08 05 0A 71 05 00 00 00 FF 07 00 01 03 62 
19:02:56.078 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:56.078 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:56.078 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 08 05 0A 71 05 00 00 00 FF 07 00 01 03 62 
19:02:56.078 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 08 05 0A 71 05 00 00 00 FF 07 00 01 03 62 
19:02:56.078 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 0A 71 05 00 00 00 FF 07 00 01 03 
19:02:56.078 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:02:56.078 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:02:56.078 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:02:56.078 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:02:56.094 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:02:56.094 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:02:56.094 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:56.094 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:02:56.094 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:02:56.094 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:02:56.125 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:02:56.125 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:02:56.125 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:02:56.125 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:02:56.125 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:02:56.125 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 03 
19:02:56.125 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:02:56.125 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:02:56.125 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:02:56.125 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:02:56.125 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:02:56.125 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:02:56.125 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:02:56.125 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:02:56.141 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:02:56.141 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:03:00.013 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53   ] - Executing scheduled rule 'check_temp'
19:03:00.029 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(auto_AC_check_time), state = 19:3:0
19:03:00.776 [WARN ] [inding.ntp.internal.NtpBinding:127  ] - couldn't establish network connection [host 'ptbtime1.ptb.de'] -> returning current sytem time instead
19:03:00.776 [DEBUG] [inding.ntp.internal.NtpBinding:84   ] - Got time from ptbtime1.ptb.de: 19:03:00 IDT יום שני 15 אוגוסט 2016
19:03:00.776 [DEBUG] [o.o.i.m.i.MyOpenHABServiceImpl:281  ] - store(Date), state = 2016-08-15T19:03:00
19:03:04.733 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
19:03:04.733 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:03:04.733 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:03:04.733 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
19:03:04.733 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
19:03:04.733 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 09 71 05 00 00 00 FF 07 03 00 
19:03:04.733 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:03:04.733 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:03:04.733 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:03:04.733 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:03:04.733 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:03:04.733 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:03:04.733 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:03:04.733 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:03:04.733 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:03:04.748 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:03:04.826 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0F 00 04 08 05 09 71 05 00 00 00 FF 07 03 00 7F 
19:03:04.826 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:03:04.826 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:03:04.826 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0F 00 04 08 05 09 71 05 00 00 00 FF 07 03 00 7F 
19:03:04.826 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0F 00 04 08 05 09 71 05 00 00 00 FF 07 03 00 7F 
19:03:04.826 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 09 71 05 00 00 00 FF 07 03 00 
19:03:04.826 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:03:04.826 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:03:04.826 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:03:04.826 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:03:04.826 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:03:04.826 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:03:04.826 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:03:04.826 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:03:04.826 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:03:04.826 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:03:04.858 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
19:03:04.858 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:03:04.858 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:03:04.873 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
19:03:04.873 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 0F 00 04 00 05 09 71 05 00 00 00 FF 07 03 00 77 
19:03:04.873 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 09 71 05 00 00 00 FF 07 03 00 
19:03:04.873 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:03:04.873 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:03:04.873 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:03:04.873 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:03:04.873 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:03:04.873 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:03:04.873 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:03:04.873 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:03:04.873 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:03:04.873 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:03:34.952 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:03:34.952 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:03:34.952 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:03:34.952 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:03:34.952 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:03:34.952 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 03 
19:03:34.952 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:03:34.952 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:03:34.952 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:03:34.952 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:03:34.952 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:03:34.952 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:03:34.952 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:03:34.952 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:03:34.952 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:03:34.952 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:03:35.030 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 08 05 0A 71 05 00 00 00 FF 07 00 01 03 62 
19:03:35.030 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:03:35.030 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:03:35.045 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 08 05 0A 71 05 00 00 00 FF 07 00 01 03 62 
19:03:35.045 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 08 05 0A 71 05 00 00 00 FF 07 00 01 03 62 
19:03:35.045 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 08 05 0A 71 05 00 00 00 FF 07 00 01 03 
19:03:35.045 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:03:35.045 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:03:35.045 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:03:35.045 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:03:35.045 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:03:35.045 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:03:35.045 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:03:35.045 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:03:35.061 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:03:35.061 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.
19:03:35.077 [DEBUG] [eController$ZWaveReceiveThread:1530 ] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:03:35.077 [DEBUG] [eController$ZWaveReceiveThread:1446 ] - Receive queue ADD: Length=1
19:03:35.077 [DEBUG] [b.z.i.protocol.ZWaveController:1194 ] - Receive queue TAKE: Length=0
19:03:35.077 [DEBUG] [o.b.z.i.protocol.SerialMessage:243  ] - Assembled message buffer = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:03:35.092 [DEBUG] [b.z.i.protocol.ZWaveController:1195 ] - Process Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 01 03 6A 
19:03:35.092 [DEBUG] [b.z.i.protocol.ZWaveController:194  ] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 05 0A 71 05 00 00 00 FF 07 00 01 03 
19:03:35.092 [DEBUG] [ApplicationCommandMessageClass:40   ] - NODE 5: Application Command Request (ALIVE:DONE)
19:03:35.092 [DEBUG] [ApplicationCommandMessageClass:58   ] - NODE 5: Incoming command class ALARM
19:03:35.092 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:82   ] - NODE 5: Received Alarm Request
19:03:35.092 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:94   ] - NODE 5: Alarm report - Value = 0
19:03:35.092 [DEBUG] [z.i.p.c.ZWaveAlarmCommandClass:113  ] - NODE 5: Alarm Type = General (0)
19:03:35.092 [DEBUG] [b.z.i.protocol.ZWaveController:648  ] - Notifying event listeners: ZWaveAlarmValueEvent
19:03:35.092 [DEBUG] [.z.internal.ZWaveActiveBinding:449  ] - ZwaveIncomingEvent
19:03:35.092 [DEBUG] [.z.internal.ZWaveActiveBinding:466  ] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
19:03:35.092 [WARN ] [.z.internal.ZWaveActiveBinding:485  ] - NODE 5: No item bound for event, endpoint = 0, command class = ALARM, value = 0, ignoring.
19:03:35.092 [DEBUG] [ApplicationCommandMessageClass:89   ] - Transaction not completed: node address inconsistent.

Zvika · August 15, 2016, 4:07pm

If you want some more log snippets, or if you prefer that I’d just upload the whole log file (is it possible?), please tell me.

chris · August 15, 2016, 6:36pm

Can you also post your item definitions - the log indicates that you don’t have anything that matches.

From a quick look, it all looks fine - there’s no difference between the different settings, but I think it is using the ‘new’ NOTIFICATION alerts and I need to take a look at how these are handled in OH1 - this is something that is being changed now in OH2 to improve the notification class…

Zvika · August 15, 2016, 7:47pm

Yeah, I know that my item definitions don’t match

I tried two sets of items.
One copied on the official example (https://github.com/openhab/openhab/wiki/Z-wave-Binding-Examples):

Number  sensor1_Movement         "תנועה: [%s]"          <present>   (gMyOpenHAB)   { zwave="4:command=sensor_binary" }
Number  sensor1_Alarm            "אזעקה: [%s]"             <fire>   (gMyOpenHAB)      { zwave="4:command=sensor_alarm" }
Number  sensor1_Lux              "תאורה: [%.2f Lux]"         <sun>   (gMyOpenHAB)       { zwave="4:command=sensor_multilevel,sensor_type=3" }
Number  sensor1_Bat              "בטריה: [%d %%]"        <energy>     (gMyOpenHAB)  { zwave="4:command=battery" }
Number  sensor1_Temp             "טמפרטורה: [%.1f °C]"  <temperature>  (gMyOpenHAB) { zwave="4:command=sensor_multilevel,sensor_type=1" }

And other copied from https://github.com/openhab/openhab/issues/4274:

Number  eye1_lux    "Lux: [%.2f Lux]" {zwave="4:command=sensor_multilevel,sensor_type=3"}
Number  eye1_temp   "Temp: [%.1f °C]" {zwave="4:command=sensor_multilevel,sensor_type=1"}
Contact eye1_motion "Motion: [%s]" {zwave="4:command=sensor_binary"}
Number  eye1_batt   "Battery: [%d %%]" {zwave="4:command=battery"}
Switch  eye1_basic  "Basic: [%s]" {zwave="4:command=basic"}
Contact eye1_alarm  "Alarm: [%s]" {zwave="4:command=alarm"}

In the first set (with ‘regular’ groups) - ‘Movement’ and ‘Alarm’ never changed.
In the second set (with ‘regular’ groups) - again ‘Motion’ and ‘Alarm’ didn’t change, but ‘basic’ was useful - it gave the actual motion info.
That’s were the ‘BC’ groups get into the picture - they seem to solve the problem.

In some point, I’ve excluded the device, and re-included it. Then, I didn’t bother to update the items, I first wanted to understand from the logs whats going on what should be bounded.

Still, in the ‘regular’ groups section, all 'Alarm’s are with value of 0 - there is no distinction between ‘alarm start’ and ‘alarm cancel’.

And what’s the meaning of these strange ‘NODE 255’ errors?
And the NODE 5: Delete return routes failed with error 0x1 errors?

chris · August 15, 2016, 8:01pm

Maybe it’s a typo, but I guess at least part of the reason there’s no item is that the device is node 5, and your item definitions are node 4?

You can see below that there is no difference between the (so called) backward compatible and normal ones. The same information is provided in both. I guess the duplication might be because you have added the controller into more than one group in the second log below? (in the first one I think you are using only the Lifeline group, which I think is probably best).

The fundamental issue though is that OH1 doesn’t currently support the NOTIFICATION class that your device is using. This is currently being added to OH2.

It’s not so strange really - node 255 is the controller. The stick rejected the command for some reason - it does happen occasionally if the controller is busy.

Zvika · August 16, 2016, 4:35am

I was too short on words…
That’s my initial items definition - and it was partially working - I got temperature and Lux, but not motion and tamper.
Then I’ve excluded and re-included my device, and its Node ID incremented to 5.
At that point I haven’t updated my items, since I still saw the problem in the log files.

Regarding your log analysis, I’m not sure that I’ve understood you (it’s too early now ), so let me summarize.
We have 3 options:
a. Using only Lifeline
b. Using Lifeline + regular(Motion, Tamper)
c. Using Lifeline + BC(Motion, Tamper).

You’re saying that option A is the best, and there should be no difference between options B and C.
Am I correct?

What about the NODE 5: Delete return routes failed with error 0x1 errors?

chris · August 16, 2016, 8:06am

The lifeline is normally meant for the controller, and normally sends all information the controller needs. The other groups are normally meant to control devices directly (eg to turn on the lights when your door opens - without going through the controller).

From what I saw in the log, there was no difference, other than you end up with duplicate messages being sent, so yes, it seems only the lifeline is needed.

As I answered above, the stick rejected the command for some reason - I don’t know why, but this can happen if the controller is busy.