I recently upgraded from OH2, but I did not migrate anything, I set up everything from scratch.
In OH2 I had 1 Hour polling of my FGT-001s (5 pieces of Fibaro Heat controllers), and it worked like a charm.
Now it does not.
The items are not updating, even though I am using the same setup.
It seems that polling works, somewhat - see the Z-Wave logs below. At least I receive temperature updates (nothing else though, like battery, etc), but the linked items are not updated - values remain the same, and there’s no corresponding entry in the events.log.
If I re-initialize a device, or my Aeotec Z-Stick, only then I get new values.
ZWave.log:
2021-03-06 09:52:29.241 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 60 0D 02 01 31 05 01 22 00 E5 78
2021-03-06 09:52:29.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 60 0D 02 01 31 05 01 22 00 E5
2021-03-06 09:52:29.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 60 0D 02 01 31 05 01 22 00 E5
2021-03-06 09:52:29.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-03-06 09:52:29.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2021-03-06 09:52:29.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2021-03-06 09:52:29.247 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2021-03-06 09:52:29.247 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 2
2021-03-06 09:52:29.248 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2021-03-06 09:52:29.248 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2021-03-06 09:52:29.248 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Temperature(1), Scale = 0
2021-03-06 09:52:29.249 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 22.9
2021-03-06 09:52:29.249 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2021-03-06 09:52:29.250 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=22.9
2021-03-06 09:52:29.251 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:ff1eb51c7c:node5:sensor_temperature2 to 22.9 °C [QuantityType]
2021-03-06 09:52:29.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2021-03-06 09:52:29.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@44bcf6.
2021-03-06 09:52:29.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-03-06 09:52:29.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-03-06 09:52:29.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-03-06 09:52:29.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-03-06 09:53:53.418 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
2021-03-06 10:01:51.602 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 06 0A 60 0D 02 01 31 05 01 22 00 E7 79
2021-03-06 10:01:51.605 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0A 60 0D 02 01 31 05 01 22 00 E7
2021-03-06 10:01:51.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0A 60 0D 02 01 31 05 01 22 00 E7
2021-03-06 10:01:51.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-03-06 10:01:51.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2021-03-06 10:01:51.608 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2021-03-06 10:01:51.608 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2021-03-06 10:01:51.609 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 2
2021-03-06 10:01:51.609 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2021-03-06 10:01:51.610 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2021-03-06 10:01:51.610 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Type = Temperature(1), Scale = 0
2021-03-06 10:01:51.611 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Value = 23.1
2021-03-06 10:01:51.611 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2021-03-06 10:01:51.612 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=23.1
2021-03-06 10:01:51.613 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:ff1eb51c7c:node6:sensor_temperature2 to 23.1 °C [QuantityType]
2021-03-06 10:01:51.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2021-03-06 10:01:51.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1cf5303.
2021-03-06 10:01:51.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-03-06 10:01:51.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-03-06 10:01:51.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-03-06 10:01:51.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-03-06 10:05:02.579 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling...
2021-03-06 10:10:36.917 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling...
2021-03-06 10:17:07.031 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
2021-03-06 10:20:16.083 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...
2021-03-06 10:23:53.418 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
2021-03-06 10:35:02.579 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling...
2021-03-06 10:40:36.917 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling...
2021-03-06 10:47:07.031 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
2021-03-06 10:50:16.083 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...
2021-03-06 10:53:53.418 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
events.log - when I re-initialize the Z-Stick:
2021-03-05 21:06:18.273 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node3' changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2021-03-05 21:06:18.488 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:ff1eb51c7c' changed from UNINITIALIZED to INITIALIZING
2021-03-05 21:06:18.508 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:ff1eb51c7c' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2021-03-05 21:06:18.618 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node6' changed from UNINITIALIZED to INITIALIZING
2021-03-05 21:06:18.621 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node7' changed from UNINITIALIZED to INITIALIZING
2021-03-05 21:06:18.629 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node5' changed from UNINITIALIZED to INITIALIZING
2021-03-05 21:06:18.633 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node2' changed from UNINITIALIZED to INITIALIZING
2021-03-05 21:06:18.646 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node7' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2021-03-05 21:06:18.650 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node3' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2021-03-05 21:06:18.656 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node6' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2021-03-05 21:06:18.659 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node5' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2021-03-05 21:06:18.669 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node3' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2021-03-05 21:06:18.673 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2021-03-05 21:06:31.797 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:ff1eb51c7c' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2021-03-05 21:06:31.808 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node7' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2021-03-05 21:06:31.818 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node6' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2021-03-05 21:06:31.825 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2021-03-05 21:06:31.837 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node5' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2021-03-05 21:06:31.840 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node3' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2021-03-05 21:06:31.856 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node2' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2021-03-05 21:06:31.900 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node3' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2021-03-05 21:06:31.975 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node6' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2021-03-05 21:06:32.048 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node5' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2021-03-05 21:06:32.075 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node7' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2021-03-05 21:06:51.030 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'KidsRoomSensorTemperature' changed from 23.8 °C to 23.7 °C
2021-03-05 21:06:52.554 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'KitchenSensorTemperature' changed from 23.4 °C to 23.5 °C
2021-03-05 21:08:22.756 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node2' changed from ONLINE: Node initialising: REQUEST_NIF to ONLINE
2021-03-05 21:08:24.242 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node3' changed from ONLINE: Node initialising: REQUEST_NIF to ONLINE
2021-03-05 21:08:25.704 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node6' changed from ONLINE: Node initialising: REQUEST_NIF to ONLINE
2021-03-05 21:08:27.321 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node5' changed from ONLINE: Node initialising: REQUEST_NIF to ONLINE
2021-03-05 21:08:28.830 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:ff1eb51c7c:node7' changed from ONLINE: Node initialising: REQUEST_NIF to ONLINE
Any ideas?
I set up the Items based on the Things, if that matters.