Fgk 10x door sensor battery level reporting

Shrikant_Lahase · July 9, 2016, 5:33am

@chris
using configuration interface i set wakeup interval to 120 seconds.
and item file entry looks like
Number door_sensor {zwave=“90:0:command=BATTERY”}

I am not getting battery level

Shrikant_Lahase · July 9, 2016, 5:43am

@chris

my logs for door sensor node are as follows:

licationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:PING)
11:11:20.468 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class SENSOR_BINARY (0x30)
11:11:20.470 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 78: Received Sensor Binary Request (v1)
11:11:20.471 [DEBUG] [.ZWaveBinarySensorCommandClass:102  ] - NODE 78: Sensor Binary report, type=Unknown, value=0
11:11:20.473 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveBinarySensorValueEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveBinarySensorCommandClass$ZWaveBinarySensorValueEvent@1dc9952
11:11:20.475 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 0
11:11:20.477 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:11:20.801 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:PING)
11:11:20.803 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class SENSOR_BINARY (0x30)
11:11:20.805 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 78: Received Sensor Binary Request (v1)
11:11:20.806 [DEBUG] [.ZWaveBinarySensorCommandClass:102  ] - NODE 78: Sensor Binary report, type=Unknown, value=255
11:11:20.808 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveBinarySensorValueEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveBinarySensorCommandClass$ZWaveBinarySensorValueEvent@183e03c
11:11:20.812 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
11:11:20.814 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:12:01.701 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 78: Polling list: item SENSOR_DOOR_162_163_78_1 is not completed initialisation
11:12:01.702 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 78: Polling list: item BATTERY_162_163_78_1 is not completed initialisation
11:12:06.055 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 78: Polling list: item SENSOR_DOOR_162_163_78_1 is not completed initialisation
11:12:06.056 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 78: Polling list: item BATTERY_162_163_78_1 is not completed initialisation
11:13:20.560 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:PING)
11:13:20.561 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class WAKE_UP (0x84)
11:13:20.562 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136  ] - NODE 78: Received Wake Up Request
11:13:20.563 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182  ] - NODE 78: Received WAKE_UP_NOTIFICATION
11:13:20.564 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411  ] - NODE 78: Is awake with 1 messages in the wake-up queue.
11:13:20.565 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveWakeUpEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveWakeUpCommandClass$ZWaveWakeUpEvent@16afea2
11:13:20.567 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1073 ] - NODE 78: Wakeup during initialisation.
11:13:20.568 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:276  ] - NODE 78: Node advancer - PING: queue length(1), free to send(false)
11:13:20.572 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:249  ] - NODE 78: Node advancer - queued packet. Queue length is 1
11:13:20.572 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 01 00 , callbackid = 114
11:13:20.573 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:13:20.587 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:13:20.633 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 114, Status = Transmission complete and ACK received(0)
11:13:20.634 [WARN ] [b.z.i.p.s.SendDataMessageClass:78   ] - NODE 78: Already processed another send data request for this callback Id, ignoring.
11:13:25.576 [DEBUG] [WaveController$ZWaveSendThread:1315 ] - NODE 78: Sending ABORT Message = 01 03 00 16 EA 
11:13:25.577 [ERROR] [WaveController$ZWaveSendThread:1330 ] - NODE 78: Timeout while sending message. Requeueing - 0 attempts left!
11:13:25.578 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
11:13:25.579 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.
11:13:25.581 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:237  ] - NODE 78: Message already on the wake-up queue. Removing original.
11:13:25.582 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.
11:13:26.393 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}
11:13:28.391 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 78: Polling list: item SENSOR_DOOR_162_163_78_1 is not completed initialisation
11:13:28.392 [DEBUG] [.z.internal.ZWaveActiveBinding:193  ] - NODE 78: Polling list: item BATTERY_162_163_78_1 is not completed initialisation

Shrikant_Lahase · July 9, 2016, 5:53am

@chris

11:15:20.717 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:207  ] - NODE 78: Node advancer - checking initialisation queue. Queue size 1.
11:15:20.718 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:213  ] - NODE 78: Node advancer - message removed from queue. Queue size 0.
11:15:20.719 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:276  ] - NODE 78: Node advancer - ASSOCIATIONS: queue length(0), free to send(true)
11:15:20.720 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - ASSOCIATIONS try 1: stageAdvanced(false)
11:15:20.721 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to SET_WAKEUP
11:15:20.721 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - SET_WAKEUP try 0: stageAdvanced(true)
11:15:20.722 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:712  ] - NODE 78: Node advancer: SET_WAKEUP - TargetNode is set to controller
11:15:20.723 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to SET_ASSOCIATION
11:15:20.724 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - SET_ASSOCIATION try 0: stageAdvanced(true)
11:15:20.936 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:763  ] - NODE 78: Node advancer: SET_ASSOCIATION - ASSOCIATION set for group 3
11:15:20.937 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to GET_CONFIGURATION
11:15:20.938 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - GET_CONFIGURATION try 0: stageAdvanced(true)
11:15:21.167 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to STATIC_END
11:15:21.168 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - STATIC_END try 0: stageAdvanced(true)
11:15:21.169 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:100  ] - NODE 78: Serialise aborted as static stages not complete
11:15:21.170 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to SESSION_START
11:15:21.171 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - SESSION_START try 0: stageAdvanced(true)
11:15:21.171 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to DYNAMIC_VALUES
11:15:21.172 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - DYNAMIC_VALUES try 0: stageAdvanced(true)
11:15:21.173 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking CRC_16_ENCAP
11:15:21.174 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking WAKE_UP
11:15:21.175 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking VERSION
11:15:21.175 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking CONFIGURATION
11:15:21.176 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking MANUFACTURER_SPECIFIC
11:15:21.177 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking SENSOR_BINARY
11:15:21.177 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:821  ] - NODE 78: Node advancer: DYNAMIC_VALUES - found    SENSOR_BINARY
11:15:21.178 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:825  ] - NODE 78: Found 1 instances of SENSOR_BINARY
11:15:21.179 [DEBUG] [.ZWaveBinarySensorCommandClass:128  ] - NODE 78: Creating new message for application command SENSOR_BINARY_GET
11:15:21.180 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
11:15:21.181 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking BATTERY
11:15:21.182 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:821  ] - NODE 78: Node advancer: DYNAMIC_VALUES - found    BATTERY
11:15:21.182 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:825  ] - NODE 78: Found 1 instances of BATTERY
11:15:21.183 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:137  ] - NODE 78: Creating new message for application command BATTERY_GET
11:15:21.184 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
11:15:21.185 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking SENSOR_ALARM
11:15:21.186 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:821  ] - NODE 78: Node advancer: DYNAMIC_VALUES - found    SENSOR_ALARM
11:15:21.186 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:825  ] - NODE 78: Found 1 instances of SENSOR_ALARM
11:15:21.189 [DEBUG] [c.ZWaveAlarmSensorCommandClass:284  ] - NODE 78: ====---- Checking alarm SMOKE - init false
11:15:21.189 [WARN ] [c.ZWaveAlarmSensorCommandClass:288  ] - NODE 78: Detected Fibaro FGK - 101 Door / Window sensor, only requesting alarm type Smoke.
11:15:21.190 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking BASIC
11:15:21.191 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking ASSOCIATION
11:15:21.192 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking NO_OPERATION
11:15:21.192 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:853  ] - NODE 78: Node advancer: DYNAMIC_VALUES - queued 2 frames
11:15:21.195 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:249  ] - NODE 78: Node advancer - queued packet. Queue length is 2
11:15:21.196 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:21.197 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 137 after 641ms/4782ms.
11:15:21.199 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 30 02 , callbackid = 138
11:15:21.213 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:15:21.261 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 138, Status = Transmission complete and ACK received(0)
11:15:21.311 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DYNAMIC_VALUES)
11:15:21.312 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class SENSOR_BINARY (0x30)
11:15:21.313 [DEBUG] [.ZWaveBinarySensorCommandClass:82   ] - NODE 78: Received Sensor Binary Request (v1)
11:15:21.314 [DEBUG] [.ZWaveBinarySensorCommandClass:102  ] - NODE 78: Sensor Binary report, type=Unknown, value=255
11:15:21.315 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveBinarySensorValueEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveBinarySensorCommandClass$ZWaveBinarySensorValueEvent@16321a6
11:15:21.316 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
11:15:21.318 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:21.324 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 30 02 , callbackid = 138, state=true]
11:15:21.328 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1050 ] - NODE 78: Node advancer - DYNAMIC_VALUES: Transaction complete (SendData:Request) success(true)
11:15:21.329 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:207  ] - NODE 78: Node advancer - checking initialisation queue. Queue size 2.
11:15:21.330 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:213  ] - NODE 78: Node advancer - message removed from queue. Queue size 1.
11:15:21.331 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:276  ] - NODE 78: Node advancer - DYNAMIC_VALUES: queue length(1), free to send(true)
11:15:21.334 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:249  ] - NODE 78: Node advancer - queued packet. Queue length is 1
11:15:21.335 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:21.336 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 138 after 136ms/4782ms.
11:15:21.339 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 139
11:15:21.354 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:15:21.428 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 139, Status = Transmission complete and ACK received(0)
11:15:21.479 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DYNAMIC_VALUES)
11:15:21.480 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class BATTERY (0x80)
11:15:21.481 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:81   ] - NODE 78: Received Battery Request
11:15:21.482 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:96   ] - NODE 78: Battery report value = 94
11:15:21.483 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveCommandClassValueEvent: org.openhab.binding.zwave.internal.protocol.event.ZWaveCommandClassValueEvent@19ac313
11:15:21.484 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 94
11:15:21.486 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:21.492 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 139, state=true]
11:15:21.494 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1050 ] - NODE 78: Node advancer - DYNAMIC_VALUES: Transaction complete (SendData:Request) success(true)
11:15:21.495 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:207  ] - NODE 78: Node advancer - checking initialisation queue. Queue size 1.
11:15:21.496 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:213  ] - NODE 78: Node advancer - message removed from queue. Queue size 0.
11:15:21.496 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:276  ] - NODE 78: Node advancer - DYNAMIC_VALUES: queue length(0), free to send(true)
11:15:21.497 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - DYNAMIC_VALUES try 1: stageAdvanced(false)
11:15:21.498 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking CRC_16_ENCAP
11:15:21.499 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking WAKE_UP
11:15:21.500 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking VERSION
11:15:21.500 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking CONFIGURATION
11:15:21.501 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking MANUFACTURER_SPECIFIC
11:15:21.502 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking SENSOR_BINARY
11:15:21.503 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:821  ] - NODE 78: Node advancer: DYNAMIC_VALUES - found    SENSOR_BINARY
11:15:21.503 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:825  ] - NODE 78: Found 1 instances of SENSOR_BINARY
11:15:21.504 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking BATTERY
11:15:21.505 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:821  ] - NODE 78: Node advancer: DYNAMIC_VALUES - found    BATTERY
11:15:21.506 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:825  ] - NODE 78: Found 1 instances of BATTERY
11:15:21.506 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking SENSOR_ALARM
11:15:21.507 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:821  ] - NODE 78: Node advancer: DYNAMIC_VALUES - found    SENSOR_ALARM
11:15:21.508 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:825  ] - NODE 78: Found 1 instances of SENSOR_ALARM
11:15:21.509 [DEBUG] [c.ZWaveAlarmSensorCommandClass:284  ] - NODE 78: ====---- Checking alarm SMOKE - init false
11:15:21.510 [WARN ] [c.ZWaveAlarmSensorCommandClass:288  ] - NODE 78: Detected Fibaro FGK - 101 Door / Window sensor, only requesting alarm type Smoke.
11:15:21.511 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking BASIC
11:15:21.512 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking ASSOCIATION
11:15:21.512 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:818  ] - NODE 78: Node advancer: DYNAMIC_VALUES - checking NO_OPERATION
11:15:21.513 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:853  ] - NODE 78: Node advancer: DYNAMIC_VALUES - queued 0 frames
11:15:21.514 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:905  ] - NODE 78: Node advancer - advancing to DONE
11:15:21.515 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:342  ] - NODE 78: Node advancer: loop - DONE try 0: stageAdvanced(true)
11:15:21.516 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:107  ] - NODE 78: Serializing to file etc/zwave/node78.xml
11:15:21.535 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:864  ] - NODE 78: Node advancer: Initialisation complete!
11:15:21.536 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveInitializationCompletedEvent: org.openhab.binding.zwave.internal.protocol.event.ZWaveInitializationCompletedEvent@66a373
11:15:21.537 [DEBUG] [.z.internal.ZWaveActiveBinding:435  ] - NODE 78: ZWaveIncomingEvent Called, Network Event, Init Done. Setting device ready.
11:15:21.547 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:21.547 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:21.548 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 139 after 208ms/4782ms.
11:15:22.494 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:506  ] - NODE 78: No more messages, go back to sleep
11:15:22.495 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:202  ] - NODE 78: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
11:15:22.497 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
11:15:22.502 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 140
11:15:23.545 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:15:23.590 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 140, Status = Transmission complete and ACK received(0)
11:15:23.597 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 140, state=true]
11:15:23.600 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:372  ] - NODE 78: Went to sleep
11:15:23.602 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
11:15:23.603 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:15:23.605 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 140 after 1103ms/4782ms.
11:15:23.608 [DEBUG] [.b.z.i.c.ZWaveBatteryConverter:60   ] - NODE 78: Generating poll message for BATTERY endpoint 0
11:15:23.609 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:137  ] - NODE 78: Creating new message for application command BATTERY_GET
11:15:23.611 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
11:15:23.614 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.
11:15:31.793 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}

11:17:17.596 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
11:17:17.597 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class WAKE_UP (0x84)
11:17:17.598 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136  ] - NODE 78: Received Wake Up Request
11:17:17.598 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182  ] - NODE 78: Received WAKE_UP_NOTIFICATION
11:17:17.599 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411  ] - NODE 78: Is awake with 1 messages in the wake-up queue.
11:17:17.600 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveWakeUpEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveWakeUpCommandClass$ZWaveWakeUpEvent@1099758
11:17:17.602 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:17:17.606 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 150
11:17:17.621 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:17:17.668 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 150, Status = Transmission complete and ACK received(0)
11:17:17.718 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
11:17:17.719 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class BATTERY (0x80)
11:17:17.719 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:81   ] - NODE 78: Received Battery Request
11:17:17.720 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:96   ] - NODE 78: Battery report value = 94
11:17:17.721 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveCommandClassValueEvent: org.openhab.binding.zwave.internal.protocol.event.ZWaveCommandClassValueEvent@16a46e5
11:17:17.722 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 94
11:17:17.724 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:17:17.727 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 150, state=true]
11:17:17.731 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:17:17.732 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 150 after 124ms/4782ms.
11:17:18.189 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}
11:17:18.731 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:506  ] - NODE 78: No more messages, go back to sleep
11:17:18.732 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:202  ] - NODE 78: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
11:17:18.733 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
11:17:18.739 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 151
11:17:18.753 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:17:18.800 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 151, Status = Transmission complete and ACK received(0)
11:17:18.804 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 151, state=true]
11:17:18.806 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:372  ] - NODE 78: Went to sleep
11:17:18.806 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
11:17:18.807 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:17:18.808 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 151 after 68ms/4782ms.
11:17:31.101 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}


11:18:18.234 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}
11:19:16.521 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
11:19:16.522 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class WAKE_UP (0x84)
11:19:16.523 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136  ] - NODE 78: Received Wake Up Request
11:19:16.523 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182  ] - NODE 78: Received WAKE_UP_NOTIFICATION
11:19:16.524 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411  ] - NODE 78: Is awake with 0 messages in the wake-up queue.
11:19:16.525 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveWakeUpEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveWakeUpCommandClass$ZWaveWakeUpEvent@d98b7c
11:19:16.527 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:19:17.528 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:506  ] - NODE 78: No more messages, go back to sleep
11:19:17.528 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:202  ] - NODE 78: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
11:19:17.529 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
11:19:17.533 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 153
11:19:17.547 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
11:19:17.594 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 153, Status = Transmission complete and ACK received(0)
11:19:17.598 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 153, state=true]
11:19:17.601 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:372  ] - NODE 78: Went to sleep
11:19:17.602 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
11:19:17.603 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
11:19:17.604 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 153 after 70ms/4782ms.
11:19:17.894 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}
11:19:30.814 [DEBUG] [ApplicationCommandMessageClass:117  ] - NODE 78: Transaction not completed: node address inconsistent.  lastSent=255, incoming={}

Shrikant_Lahase · July 9, 2016, 6:04am

@chris

sorry for reporting same issue in multiple thread…
now onwards i will follow current thread only

chris · July 9, 2016, 6:04am

For starters the battery level will only be downloaded every hour maximum so setting the time to 120 seconds won’t make the battery level be sent every 120 seconds unless the device does this through some other configuration setting.

chris · July 9, 2016, 6:07am

For battery command class the refresh is fixed at 1 hour.

Shrikant_Lahase · July 9, 2016, 6:23am

ok chris

but just few minutes ago, i added refresh interval in items file 120 seconds and now i am getting battery level after every 120 seconds.

chris · July 9, 2016, 6:25am

What version of the binding are you using? Have you modified the binding at all? The debug messages now don’t look correct and can’t be processed by the processor.

chris · July 9, 2016, 6:27am

It may be that for this device it always sends the battery when it wakes up. Unless you have modified the binding, it will not request the battery level more often than every 3600 seconds - this is fixed for the battery command class.

    int getRefreshInterval() {
        return REFRESH_INTERVAL;
    }

In this code the interval is 3600 seconds and it’s fixed.

Shrikant_Lahase · July 9, 2016, 6:29am

openhab version is 1.8.0
and in my binding source code also it is given :

public class ZWaveBatteryConverter extends ZWaveCommandClassConverter {

private static final Logger logger = LoggerFactory.getLogger(ZWaveBatteryConverter.class);
private static final int REFRESH_INTERVAL = 3600; // refresh interval in seconds for the battery level;

/**
 * Constructor. Creates a new instance of the {@link ZWaveBatteryConverter} class.
 * @param controller the {@link ZWaveController} to use for sending messages.
 * @param eventPublisher the {@link EventPublisher} to use to publish events.
 */
public ZWaveBatteryConverter(ZWaveController controller, EventPublisher eventPublisher) {
    super(controller, eventPublisher);
    
    // State and commmand converters used by this converter. 
    this.addStateConverter(new BatteryDecimalTypeConverter());
    this.addStateConverter(new BatteryPercentTypeConverter());
}

Shrikant_Lahase · July 9, 2016, 6:30am

my item entry:

Number BATTERY_162_163_78_1 {zwave=“78:0:command=BATTERY,refresh_interval=120”}

chris · July 9, 2016, 6:31am

Have you changed the code at all? The messages that log data being transmitted is now different than expected and it stops the log reader from working. Currently I can’t process your logs - sorry.

Shrikant_Lahase · July 9, 2016, 6:32am

default refresh interval is 3600 but when i gave refresh_interval=120 in item after that it started to report every 120 seconds

chris · July 9, 2016, 6:35am

As I said, some devices will automatically report battery interval when they wake up - this is nothing to do with refresh. As I showed earlier, the refresh interval is fixed at 3600 seconds so the binding won’t poll at a different rate.

I would recommend that you upgrade to the latest version, and provide a log that can be read from that version so we can see what is going on.

Shrikant_Lahase · July 9, 2016, 6:39am

ok chris…thanks for info
i will get back to you soon with latest binding logs

Shrikant_Lahase · July 9, 2016, 6:53am

these are some logs with my current binding:

12:05:59.235 [DEBUG] [.b.z.i.c.ZWaveBatteryConverter:60   ] - NODE 78: Generating poll message for BATTERY endpoint 0
12:05:59.236 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:137  ] - NODE 78: Creating new message for application command BATTERY_GET
12:05:59.237 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
12:05:59.239 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.

12:06:46.915 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
12:06:46.917 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class WAKE_UP (0x84)
12:06:46.918 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136  ] - NODE 78: Received Wake Up Request
12:06:46.919 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182  ] - NODE 78: Received WAKE_UP_NOTIFICATION
12:06:46.920 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411  ] - NODE 78: Is awake with 1 messages in the wake-up queue.
12:06:46.922 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveWakeUpEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveWakeUpCommandClass$ZWaveWakeUpEvent@12dcb1c
12:06:46.926 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:06:46.933 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 34
12:06:46.952 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
12:06:47.039 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 34, Status = Transmission complete and ACK received(0)
12:06:47.092 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
12:06:47.094 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class BATTERY (0x80)
12:06:47.096 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:81   ] - NODE 78: Received Battery Request
12:06:47.098 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:96   ] - NODE 78: Battery report value = 94
12:06:47.100 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveCommandClassValueEvent: org.openhab.binding.zwave.internal.protocol.event.ZWaveCommandClassValueEvent@fdc453
12:06:47.104 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 94
12:06:47.107 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:06:47.114 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 34, state=true]
12:06:47.116 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:06:47.117 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 34 after 184ms/5123ms.
12:06:48.116 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:506  ] - NODE 78: No more messages, go back to sleep
12:06:48.118 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:202  ] - NODE 78: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
12:06:48.119 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
12:06:48.126 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 35
12:06:49.148 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
12:06:49.193 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 35, Status = Transmission complete and ACK received(0)
12:06:49.198 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 35, state=true]
12:06:49.201 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:372  ] - NODE 78: Went to sleep
12:06:49.202 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
12:06:49.203 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:06:49.204 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 35 after 1077ms/5123ms.
12:07:59.263 [DEBUG] [.b.z.i.c.ZWaveBatteryConverter:60   ] - NODE 78: Generating poll message for BATTERY endpoint 0
12:07:59.264 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:137  ] - NODE 78: Creating new message for application command BATTERY_GET
12:07:59.266 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
12:07:59.268 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.
12:08:47.142 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
12:08:47.143 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class WAKE_UP (0x84)
12:08:47.144 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136  ] - NODE 78: Received Wake Up Request
12:08:47.145 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182  ] - NODE 78: Received WAKE_UP_NOTIFICATION
12:08:47.146 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411  ] - NODE 78: Is awake with 1 messages in the wake-up queue.
12:08:47.148 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveWakeUpEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveWakeUpCommandClass$ZWaveWakeUpEvent@1a1075b
12:08:47.150 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:08:47.156 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 44
12:08:47.174 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
12:08:49.224 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
12:08:49.226 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class BATTERY (0x80)
12:08:49.227 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:81   ] - NODE 78: Received Battery Request
12:08:49.228 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:96   ] - NODE 78: Battery report value = 94
12:08:49.230 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveCommandClassValueEvent: org.openhab.binding.zwave.internal.protocol.event.ZWaveCommandClassValueEvent@191ce69
12:08:49.233 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 94
12:08:49.235 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:08:49.253 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 44, Status = Transmission complete and ACK received(0)
12:08:52.158 [ERROR] [WaveController$ZWaveSendThread:1330 ] - NODE 78: Timeout while sending message. Requeueing - 2 attempts left!
12:08:52.159 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
12:08:52.160 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.
12:09:59.289 [DEBUG] [.b.z.i.c.ZWaveBatteryConverter:60   ] - NODE 78: Generating poll message for BATTERY endpoint 0
12:09:59.290 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:137  ] - NODE 78: Creating new message for application command BATTERY_GET
12:09:59.292 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
12:09:59.295 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:237  ] - NODE 78: Message already on the wake-up queue. Removing original.
12:09:59.297 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241  ] - NODE 78: Putting message SendData in wakeup queue.



12:10:44.600 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
12:10:44.602 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class WAKE_UP (0x84)
12:10:44.603 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136  ] - NODE 78: Received Wake Up Request
12:10:44.605 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182  ] - NODE 78: Received WAKE_UP_NOTIFICATION
12:10:44.606 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411  ] - NODE 78: Is awake with 1 messages in the wake-up queue.
12:10:44.608 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveWakeUpEvent: org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveWakeUpCommandClass$ZWaveWakeUpEvent@1705826
12:10:44.611 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:10:44.617 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 50
12:10:44.635 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
12:10:44.655 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 50, Status = Transmission complete and ACK received(0)
12:10:44.674 [DEBUG] [ApplicationCommandMessageClass:39   ] - NODE 78: Application Command Request (ALIVE:DONE)
12:10:44.676 [DEBUG] [ApplicationCommandMessageClass:138  ] - NODE 78: Incoming command class BATTERY (0x80)
12:10:44.678 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:81   ] - NODE 78: Received Battery Request
12:10:44.679 [DEBUG] [i.p.c.ZWaveBatteryCommandClass:96   ] - NODE 78: Battery report value = 94
12:10:44.681 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveCommandClassValueEvent: org.openhab.binding.zwave.internal.protocol.event.ZWaveCommandClassValueEvent@8d2da7
12:10:44.684 [DEBUG] [.z.internal.ZWaveActiveBinding:460  ] - NODE 78: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 94
12:10:44.687 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:10:44.698 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 80 02 , callbackid = 50, state=true]
12:10:44.701 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:10:44.703 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 50 after 84ms/5123ms.
12:10:45.701 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:506  ] - NODE 78: No more messages, go back to sleep
12:10:45.702 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:202  ] - NODE 78: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
12:10:45.704 [DEBUG] [o.b.z.i.protocol.SerialMessage:111  ] - NODE 78: Creating empty message of class = SendData (0x13), type = Request (0x00)
12:10:45.711 [DEBUG] [WaveController$ZWaveSendThread:1277 ] - NODE 78: Sending REQUEST Message = Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 51
12:10:46.730 [DEBUG] [b.z.i.p.s.SendDataMessageClass:39   ] - NODE 78: Sent Data successfully placed on stack.
12:10:46.747 [DEBUG] [b.z.i.p.s.SendDataMessageClass:75   ] - NODE 78: SendData Request. CallBack ID = 51, Status = Transmission complete and ACK received(0)
12:10:46.752 [DEBUG] [b.z.i.protocol.ZWaveController:616  ] - NODE 78: Notifying event listeners: ZWaveTransactionCompletedEvent: ZWaveTransactionCompletedEvent [completedMessage=Message: class = SendData (0x13), type = Request (0x00), payload = 4E 02 84 08 , callbackid = 51, state=true]
12:10:46.756 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:372  ] - NODE 78: Went to sleep
12:10:46.757 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435  ] - NODE 78: Is sleeping
12:10:46.758 [DEBUG] [b.z.i.protocol.ZWaveController:627  ] - NODE 78: Processing event ourselves
12:10:46.760 [DEBUG] [WaveController$ZWaveSendThread:1349 ] - NODE 78: Response processed for callback id 51 after 1048ms/5123ms.

chris · July 9, 2016, 7:02am

Please can I ask you to use the latest binding - either 1.8.3, or 1.9.0. Currently I can’t process these logs - sorry. I think you are also filtering the logs to only show lines with NODE 78 - this removes some logging that is needed.

Also, when you post the logs, please format using the </> button so they are presented better (I have edited your posts to do this). Thanks

ewgor · December 17, 2016, 4:10pm

My I ask what binding are you talkin about? The zwave? I’d like also to check my fgk battery level .
Thanks.

chris · December 17, 2016, 4:26pm

Yes - the zwave binding is required to communicate with zwave devices ;).

ewgor · December 17, 2016, 7:55pm

Right, I was thinking maybe there is another special binding for the alarm setup. I have already 6 zwave devices on my network.
So to check the fgk battery status what do I need to do?
One more thing, please tell me, I would like that when my fgk is open to trigger a FGS 223 and the question is can I do this from habmin using group associates or do I need to create a rule that will handl this?
Thank you.