PST02B zwave sensor alarm not getting to item in 2.4 [SOLVED]

antimork · January 7, 2019, 7:27pm

I updated my openhab installation to 2.4 recently, and I have most things working again, with one exception: The alarm (PIR) events from my Philio PST02B sensor are not getting though to the actual Items. The weird thing is that they look fine (to me…) in the zwave debug log, but they are completely dead in the events log.

I get this in the event log when adding the channel to the item:
2019-01-07 19:51:26.427 [.ItemChannelLinkAddedEvent] - Link ‘WorkroomSensorPIR3-zwave:device:80e4d0cf:node40:alarm_burglar’ has been added.

And when I move around in front of the sensor:

2019-01-07 20:16:37.597 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 28 03 80 03 24 7E
2019-01-07 20:16:37.598 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommand
Handler[4], type=Request[0], dest=40, callback=0, payload=00 28 03 80 03 24
2019-01-07 20:16:37.598 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Re
quest[0], dest=40, callback=0, payload=00 28 03 80 03 24
2019-01-07 20:16:37.598 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-07 20:16:37.598 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Application Command Request (ALIVE:DONE)
2019-01-07 20:16:37.598 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: resetResendCount initComplete=true isDead=false
2019-01-07 20:16:37.598 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
2019-01-07 20:16:37.598 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: SECURITY NOT required on COMMAND_CLASS_BATTERY
2019-01-07 20:16:37.598 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 40: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
2019-01-07 20:16:37.598 [DEBUG] [nal.protocol.commandclass.ZWaveBatteryCommandClass] - NODE 40: Battery report value = 36
2019-01-07 20:16:37.598 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 40: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-01-07 20:16:37.598 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 40: Got a value event from Z-Wave network, endpoint = 0, command
class = COMMAND_CLASS_BATTERY, value = 36
2019-01-07 20:16:37.598 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 40: Updating channel state zwave:device:80e4d0cf:node40:battery-l
evel to 36 [DecimalType]
2019-01-07 20:16:37.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Commands processed 1.
2019-01-07 20:16:37.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Checking command org.openhab.binding.zwave.internal.protocol.
ZWaveCommandClassPayload@4c4a91f9.
2019-01-07 20:16:37.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-07 20:16:37.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-07 20:16:37.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-07 20:16:37.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-01-07 20:16:37.616 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 28 09 71 05 00 00 00 FF 07 08 00 51
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommand
Handler[4], type=Request[0], dest=40, callback=0, payload=00 28 09 71 05 00 00 00 FF 07 08 00
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=40, callback=0, payload=00 28 09 71 05 00 00 00 FF 07 08 00
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Application Command Request (ALIVE:DONE)
2019-01-07 20:16:37.617 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: resetResendCount initComplete=true isDead=false
2019-01-07 20:16:37.617 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2019-01-07 20:16:37.617 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: SECURITY NOT required on COMMAND_CLASS_ALARM
2019-01-07 20:16:37.617 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 40: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2019-01-07 20:16:37.617 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 40: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2019-01-07 20:16:37.617 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 40: Alarm Type = BURGLAR (0)
2019-01-07 20:16:37.617 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 40: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2019-01-07 20:16:37.617 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 40: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255
2019-01-07 20:16:37.617 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 40: Alarm converter processing NOTIFICATION
2019-01-07 20:16:37.617 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 40: Alarm converter NOTIFICATION event is 8, type OnOffType
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Commands processed 1.
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5460ef59.
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-07 20:16:37.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-01-07 20:16:37.634 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 28 05 31 05 03 01 01 EA
2019-01-07 20:16:37.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=40, callback=0, payload=00 28 05 31 05 03 01 01
2019-01-07 20:16:37.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=40, callback=0, payload=00 28 05 31 05 03 01 01
2019-01-07 20:16:37.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-07 20:16:37.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Application Command Request (ALIVE:DONE)
2019-01-07 20:16:37.635 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: resetResendCount initComplete=true isDead=false
2019-01-07 20:16:37.635 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-01-07 20:16:37.635 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 40: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-01-07 20:16:37.635 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 40: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2019-01-07 20:16:37.635 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 40: Sensor Type = Luminance(3), Scale = 0
2019-01-07 20:16:37.635 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 40: Sensor Value = 1

…so far so good (I think…).

But if I grep WorkroomSensor events.log
2019-01-07 20:16:37.635 [vent.ItemStateChangedEvent] - WorkroomSensorLight changed from 2 to 1
So I get the light update the sensor sends when it wakes up because of the alarm, but not the alarm itself…? Hmm. There is nothing in the main openhab log at this time at all.

Item line:
Switch WorkroomSensorPIR3 "Workroom PIR 3" {channel="zwave:device:80e4d0cf:node40:alarm_burglar"}

This sensor & setup worked fine in 2.3 and before. I did the required remove & re-add of the Things.

I will add more logs if needed, I have tried to boil them down to the essentials here. (I have a rather talkative zwave network…)

Has anyone seen anything like this? Or any idea of where I should look to solve this?

chris · January 11, 2019, 6:39pm

I’ve updated the database, so once this flows through you should update to the latest snapshot. You will need to delete the thing and add it back in order for the system to pick up the latest definition.

antimork · January 11, 2019, 7:17pm

Nice! I will get it as soon as it is out! Thanks!

antimork · January 15, 2019, 3:44pm

It works!

For anyone else having the problem with this sensor, I used this file. Later snapshots should work too I guess.

Thanks for the quick solution!

florian77muc · April 24, 2019, 7:04pm

I have experienced a similar problem. After browsing through the forums, I upgraded to openHAB 2.5.0#1575. That solved my issue