Aotec zstick 5 - not receiving sensor values

Hardware Home Automation
1

Hello all,

I am currently struggling with getting my Aotec TriSensor to report values into openhab. I am using the following:

  • Ubuntu 18.04 virtual machine
  • Java 11
  • Latest version of Openhab
  • Aotec zstick 5
  • Aotec TriSensor

I am trying to connect the sensor to openhab using what i understand to be a fairly regular methodology. First, i install the z-wave binding (https://www.openhab.org/addons/bindings/zwave/). Next, i go to my inbox and add the zstick as a thing, making sure to select the correct usb port (/dev/ttyACM0). I know this step works as it appears online within openhab. After that, i go back to my inbox and search for zwave devices, a process in which the Aotec TriSensor does appear and allow me to add it as a thing. This then populates the necessary channels for me (temp, battery, light, motion) and i create/add some new items to those channels so i can see any information i receive in the control section.

After completing the aforementioned steps i would have assumed that everything is fine, as the openhab and event logs don’t show anything untoward, however all the values perpetually stay at NaN or just blank, and the ui doesn’t even register that the sensor has gone offline if i take the battery out of it.

Logs:

Events

2020-02-20 11:54:07.747 [thome.event.ExtensionEvent] - Extension 'binding-zwave' has been installed.
2020-02-20 12:05:47.577 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:05:47.653 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:1a71c6d5' has been updated.
2020-02-20 12:05:47.674 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:05:56.796 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:05:56.807 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:1a71c6d5' has been updated.
2020-02-20 12:06:19.007 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:1a71c6d5:node5' has been added.
2020-02-20 12:06:19.043 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:1a71c6d5:node6' has been added.
2020-02-20 12:06:38.415 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:1a71c6d5:node6' has been removed.
2020-02-20 12:06:40.582 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:1a71c6d5:node5' has been removed.
2020-02-20 12:07:54.584 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Serial Error: Port /dev/ttyACM0 does not exist
2020-02-20 12:08:29.517 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from OFFLINE (COMMUNICATION_ERROR): Serial Error: Port /dev/ttyACM0 does not exist to ONLINE
2020-02-20 12:08:29.526 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:1a71c6d5' has been updated.
2020-02-20 12:08:33.645 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from ONLINE to REMOVING
2020-02-20 12:08:33.666 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from REMOVING to REMOVED
2020-02-20 12:08:33.728 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from REMOVED to UNINITIALIZED
2020-02-20 12:08:33.859 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:1a71c6d5' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:09:27.647 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:66de4257' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:09:27.697 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:66de4257' has been updated.
2020-02-20 12:09:27.705 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:66de4257' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:11:51.495 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:66de4257' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to REMOVING
2020-02-20 12:11:51.512 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:66de4257' changed from REMOVING to REMOVED
2020-02-20 12:11:51.545 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:66de4257' changed from REMOVED to UNINITIALIZED
2020-02-20 12:11:52.057 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:66de4257' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:12:48.331 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:12:48.356 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:12:48.367 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:12:57.416 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:12:57.447 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:15:28.309 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:f3448e9b:node2' has been added.
2020-02-20 12:15:36.456 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:f3448e9b:node2' has been removed.
2020-02-20 12:15:36.531 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:15:36.555 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:15:36.588 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to UNINITIALIZED
2020-02-20 12:15:36.652 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:15:36.718 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-02-20 12:15:36.728 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:15:36.766 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:15:36.771 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:15:36.772 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:15:36.779 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:15:36.787 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:15:36.861 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[]]
2020-02-20 12:15:36.988 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:17:38.360 [.ItemChannelLinkAddedEvent] - Link 'ZWaveNode002_SensorTemperature-zwave:device:f3448e9b:node2:sensor_temperature' has been added.
2020-02-20 12:18:04.305 [.ItemChannelLinkAddedEvent] - Link 'ZWaveNode002_SensorLuminance-zwave:device:f3448e9b:node2:sensor_luminance' has been added.
2020-02-20 12:18:14.801 [.ItemChannelLinkAddedEvent] - Link 'ZWaveNode002_MotionAlarm-zwave:device:f3448e9b:node2:alarm_motion' has been added.
2020-02-20 12:18:25.987 [.ItemChannelLinkAddedEvent] - Link 'ZWaveNode002_BatteryLevel-zwave:device:f3448e9b:node2:battery-level' has been added.
2020-02-20 12:21:24.914 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:24.953 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:24.959 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:24.975 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:24.990 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:25.003 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:25.009 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:25.024 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:25.034 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:25.046 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:25.054 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:25.066 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:25.103 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:25.125 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:25.131 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:26.397 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:27.016 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from ONLINE to UNINITIALIZED
2020-02-20 12:21:27.024 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from UNINITIALIZED to ONLINE
2020-02-20 12:21:27.032 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from ONLINE to OFFLINE
2020-02-20 12:21:27.053 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from OFFLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:21:27.641 [temChannelLinkRemovedEvent] - Link 'WindowSensor_W => mqtt:topic:5aa31257:window' has been removed.
2020-02-20 12:21:27.655 [temChannelLinkRemovedEvent] - Link 'Test_Item_1 => mqtt:topic:dbbe88fc:test-thing-1-text' has been removed.
2020-02-20 12:21:27.656 [temChannelLinkRemovedEvent] - Link 'GlassBreakSensor => mqtt:topic:562dccd3:glass_break_sensor' has been removed.
2020-02-20 12:21:27.662 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_BatteryLevel => zwave:device:f3448e9b:node2:battery-level' has been removed.
2020-02-20 12:21:27.664 [temChannelLinkRemovedEvent] - Link 'WindowSensor => mqtt:topic:5aa31257:t' has been removed.
2020-02-20 12:21:27.672 [temChannelLinkRemovedEvent] - Link 'DoorLock => mqtt:topic:0d81385e:door_lock' has been removed.
2020-02-20 12:21:27.687 [temChannelLinkRemovedEvent] - Link 'Test_Item_2 => mqtt:topic:be045d4a:test_thing_2' has been removed.
2020-02-20 12:21:27.691 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_MotionAlarm => zwave:device:f3448e9b:node2:alarm_motion' has been removed.
2020-02-20 12:21:27.701 [temChannelLinkRemovedEvent] - Link 'MotionSensor => mqtt:topic:75f9a474:motion_sensor' has been removed.
2020-02-20 12:21:27.707 [temChannelLinkRemovedEvent] - Link 'TemperatureSensor_Ts => mqtt:topic:fe331cb3:temperature_sensor' has been removed.
2020-02-20 12:21:27.711 [temChannelLinkRemovedEvent] - Link 'PleaseWork_1 => mqtt:topic:25047df4:1' has been removed.
2020-02-20 12:21:27.713 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_SensorTemperature => zwave:device:f3448e9b:node2:sensor_temperature' has been removed.
2020-02-20 12:21:27.731 [temChannelLinkRemovedEvent] - Link 'DoorSensor => mqtt:topic:ac984435:door_sensor' has been removed.
2020-02-20 12:21:27.759 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_SensorLuminance => zwave:device:f3448e9b:node2:sensor_luminance' has been removed.
2020-02-20 12:22:08.815 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:08.968 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from INITIALIZING to OFFLINE
2020-02-20 12:22:10.387 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from OFFLINE to ONLINE
2020-02-20 12:22:10.424 [me.event.ThingUpdatedEvent] - Thing 'mqtt:broker:82cd2be3' has been updated.
2020-02-20 12:22:11.267 [vent.ItemStateChangedEvent] - DoorLock changed from NULL to door_secured
2020-02-20 12:22:11.291 [vent.ItemStateChangedEvent] - WindowSensor_W changed from NULL to CLOSED
2020-02-20 12:22:11.305 [vent.ItemStateChangedEvent] - GlassBreakSensor changed from NULL to no_event
2020-02-20 12:22:11.314 [vent.ItemStateChangedEvent] - MotionSensor changed from NULL to no_event
2020-02-20 12:22:11.365 [vent.ItemStateChangedEvent] - DoorSensor changed from NULL to CLOSED
2020-02-20 12:22:11.447 [vent.ItemStateChangedEvent] - TemperatureSensor_Ts changed from NULL to 20.000418 °C
2020-02-20 12:22:12.215 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:12.366 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from INITIALIZING to ONLINE
2020-02-20 12:22:12.382 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:12.393 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from INITIALIZING to ONLINE
2020-02-20 12:22:12.398 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:12.407 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from INITIALIZING to ONLINE
2020-02-20 12:22:12.422 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:12.435 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from INITIALIZING to ONLINE
2020-02-20 12:22:12.448 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:12.499 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from INITIALIZING to ONLINE
2020-02-20 12:22:12.509 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:12.517 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from INITIALIZING to ONLINE
2020-02-20 12:22:15.254 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2020-02-20 12:22:15.950 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:22:15.962 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:22:16.011 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2020-02-20 12:22:16.020 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:22:25.993 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:22:26.022 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:22:26.042 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:22:26.103 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:23:19.069 [ome.event.ItemUpdatedEvent] - Item 'ZWaveNode002_SensorTemperature' has been updated.
2020-02-20 12:24:21.871 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_SensorTemperature => zwave:device:f3448e9b:node2:sensor_temperature' has been removed.
2020-02-20 12:24:28.480 [ome.event.ItemUpdatedEvent] - Item 'ZWaveNode002_SensorTemperature' has been updated.
2020-02-20 12:24:28.805 [.ItemChannelLinkAddedEvent] - Link 'ZWaveNode002_SensorTemperature-zwave:device:f3448e9b:node2:sensor_temperature' has been added.
2020-02-20 12:26:17.535 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:26:17.788 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:26:17.812 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:26:26.606 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:26:26.609 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:26:26.618 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:26:26.628 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:26:26.647 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2020-02-20 12:26:43.562 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:26:43.571 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE: Node initialising: REQUEST_NIF to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:26:43.597 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:26:53.245 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:26:53.253 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:26:53.275 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:26:53.311 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF
2020-02-20 12:26:56.890 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:26:56.898 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE: Node initialising: REQUEST_NIF to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:26:56.924 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:27:05.982 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:27:05.985 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:27:06.002 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:27:06.019 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:34:29.382 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:34:29.457 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=wakeup_node, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null], ConfigStatusMessage [parameterName=wakeup_interval, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]
2020-02-20 12:37:35.969 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:37:35.992 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=wakeup_node, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null], ConfigStatusMessage [parameterName=wakeup_interval, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]
2020-02-20 12:38:41.765 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:38:41.802 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=wakeup_node, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null], ConfigStatusMessage [parameterName=wakeup_interval, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]
2020-02-20 12:40:14.926 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:40:15.300 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:40:15.311 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:45:54.879 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to OFFLINE (COMMUNICATION_ERROR): Serial Error: Port /dev/ttyACM0 does not exist
2020-02-20 12:47:48.386 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:48.452 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.459 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:48.491 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.494 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:48.510 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.523 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:48.540 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.551 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:48.576 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.582 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:48.615 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.687 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to UNINITIALIZED
2020-02-20 12:47:48.708 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:48.724 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (COMMUNICATION_ERROR): Serial Error: Port /dev/ttyACM0 does not exist to UNINITIALIZED
2020-02-20 12:47:48.756 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:49.428 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from ONLINE to UNINITIALIZED
2020-02-20 12:47:49.537 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from UNINITIALIZED to ONLINE
2020-02-20 12:47:49.560 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from ONLINE to OFFLINE
2020-02-20 12:47:49.576 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from OFFLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-02-20 12:47:50.227 [temChannelLinkRemovedEvent] - Link 'WindowSensor_W => mqtt:topic:5aa31257:window' has been removed.
2020-02-20 12:47:50.228 [temChannelLinkRemovedEvent] - Link 'Test_Item_1 => mqtt:topic:dbbe88fc:test-thing-1-text' has been removed.
2020-02-20 12:47:50.228 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_BatteryLevel => zwave:device:f3448e9b:node2:battery-level' has been removed.
2020-02-20 12:47:50.228 [temChannelLinkRemovedEvent] - Link 'GlassBreakSensor => mqtt:topic:562dccd3:glass_break_sensor' has been removed.
2020-02-20 12:47:50.228 [temChannelLinkRemovedEvent] - Link 'WindowSensor => mqtt:topic:5aa31257:t' has been removed.
2020-02-20 12:47:50.229 [temChannelLinkRemovedEvent] - Link 'DoorLock => mqtt:topic:0d81385e:door_lock' has been removed.
2020-02-20 12:47:50.229 [temChannelLinkRemovedEvent] - Link 'Test_Item_2 => mqtt:topic:be045d4a:test_thing_2' has been removed.
2020-02-20 12:47:50.229 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_MotionAlarm => zwave:device:f3448e9b:node2:alarm_motion' has been removed.
2020-02-20 12:47:50.229 [temChannelLinkRemovedEvent] - Link 'MotionSensor => mqtt:topic:75f9a474:motion_sensor' has been removed.
2020-02-20 12:47:50.229 [temChannelLinkRemovedEvent] - Link 'TemperatureSensor_Ts => mqtt:topic:fe331cb3:temperature_sensor' has been removed.
2020-02-20 12:47:50.253 [temChannelLinkRemovedEvent] - Link 'PleaseWork_1 => mqtt:topic:25047df4:1' has been removed.
2020-02-20 12:47:50.265 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_SensorTemperature => zwave:device:f3448e9b:node2:sensor_temperature' has been removed.
2020-02-20 12:47:50.278 [temChannelLinkRemovedEvent] - Link 'DoorSensor => mqtt:topic:ac984435:door_sensor' has been removed.
2020-02-20 12:47:50.286 [temChannelLinkRemovedEvent] - Link 'ZWaveNode002_SensorLuminance => zwave:device:f3448e9b:node2:sensor_luminance' has been removed.
2020-02-20 12:48:37.804 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:37.909 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from INITIALIZING to OFFLINE
2020-02-20 12:48:39.323 [hingStatusInfoChangedEvent] - 'mqtt:broker:82cd2be3' changed from OFFLINE to ONLINE
2020-02-20 12:48:39.343 [me.event.ThingUpdatedEvent] - Thing 'mqtt:broker:82cd2be3' has been updated.
2020-02-20 12:48:40.148 [vent.ItemStateChangedEvent] - DoorLock changed from NULL to door_secured
2020-02-20 12:48:40.166 [vent.ItemStateChangedEvent] - WindowSensor_W changed from NULL to CLOSED
2020-02-20 12:48:40.175 [vent.ItemStateChangedEvent] - GlassBreakSensor changed from NULL to no_event
2020-02-20 12:48:40.186 [vent.ItemStateChangedEvent] - MotionSensor changed from NULL to no_event
2020-02-20 12:48:40.236 [vent.ItemStateChangedEvent] - DoorSensor changed from NULL to CLOSED
2020-02-20 12:48:40.298 [vent.ItemStateChangedEvent] - TemperatureSensor_Ts changed from NULL to 20.000418 °C
2020-02-20 12:48:40.880 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:41.052 [hingStatusInfoChangedEvent] - 'mqtt:topic:562dccd3' changed from INITIALIZING to ONLINE
2020-02-20 12:48:41.067 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:41.069 [hingStatusInfoChangedEvent] - 'mqtt:topic:0d81385e' changed from INITIALIZING to ONLINE
2020-02-20 12:48:41.078 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:41.084 [hingStatusInfoChangedEvent] - 'mqtt:topic:75f9a474' changed from INITIALIZING to ONLINE
2020-02-20 12:48:41.098 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:41.110 [hingStatusInfoChangedEvent] - 'mqtt:topic:fe331cb3' changed from INITIALIZING to ONLINE
2020-02-20 12:48:41.127 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:41.136 [hingStatusInfoChangedEvent] - 'mqtt:topic:ac984435' changed from INITIALIZING to ONLINE
2020-02-20 12:48:41.144 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:41.165 [hingStatusInfoChangedEvent] - 'mqtt:topic:5aa31257' changed from INITIALIZING to ONLINE
2020-02-20 12:48:44.459 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2020-02-20 12:48:44.592 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from UNINITIALIZED to INITIALIZING
2020-02-20 12:48:44.617 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:48:44.684 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2020-02-20 12:48:44.715 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2020-02-20 12:48:49.679 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to OFFLINE (COMMUNICATION_ERROR): Serial Error: Port /dev/ttyACM0 does not exist
2020-02-20 12:49:27.719 [hingStatusInfoChangedEvent] - 'zwave:serial_zstick:f3448e9b' changed from OFFLINE (COMMUNICATION_ERROR): Serial Error: Port /dev/ttyACM0 does not exist to ONLINE
2020-02-20 12:49:27.767 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2020-02-20 12:49:27.803 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:f3448e9b:node2' has been updated.
2020-02-20 12:49:27.810 [me.event.ThingUpdatedEvent] - Thing 'zwave:serial_zstick:f3448e9b' has been updated.
2020-02-20 12:49:27.865 [hingStatusInfoChangedEvent] - 'zwave:device:f3448e9b:node2' changed from ONLINE to ONLINE: Node initialising: REQUEST_NIF

Openhab

2020-02-20 12:05:52.889 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:05:52.890 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:06:18.974 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known.
2020-02-20 12:06:19.015 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:1a71c6d5:node5' to inbox.
2020-02-20 12:06:19.023 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery could not resolve to a thingType! Manufacturer data not known.
2020-02-20 12:06:19.051 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:1a71c6d5:node6' to inbox.
2020-02-20 12:08:24.644 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:08:24.647 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:09:27.575 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2020-02-20 12:09:32.763 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:09:32.767 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:12:48.238 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2020-02-20 12:12:53.697 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:12:53.698 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:15:28.308 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:f3448e9b:node2' to inbox.
2020-02-20 12:17:37.336 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorTemperature'.
2020-02-20 12:18:03.637 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorLuminance'.
2020-02-20 12:18:14.118 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_MotionAlarm'.
2020-02-20 12:18:25.297 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_BatteryLevel'.
2020-02-20 12:21:23.300 [WARN ] [org.jline                           ] - The Parser of class org.apache.karaf.shell.impl.console.ConsoleSessionImpl$$Lambda$908/0x0000000100f0d840 does not support the CompletingParsedLine interface. Completion with escaped or quoted words won't work correctly.
2020-02-20 12:21:24.824 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homie.internal.discovery.Homie300Discovery@3a3b82af from  discovery topic homeassistant/# on broker mqtt:broker:82cd2be3 but topic not registered for listener. Check discovery logic!
2020-02-20 12:21:24.869 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@7b95542c from  discovery topic +/+/$homie on broker mqtt:broker:82cd2be3 but topic not registered at all. Check discovery logic!
2020-02-20 12:21:24.871 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@7b95542c from  discovery topic homeassistant/# on broker mqtt:broker:82cd2be3 but topic not registered at all. Check discovery logic!
2020-02-20 12:21:24.873 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@7b95542c from  discovery topic +/+/$homie on broker mqtt:broker:82cd2be3 but topic not registered at all. Check discovery logic!
2020-02-20 12:21:27.139 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Stopped Paper UI
2020-02-20 12:21:27.162 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Stopped Home Builder
2020-02-20 12:21:27.184 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel
2020-02-20 12:21:27.212 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI
2020-02-20 12:21:27.280 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard
2020-02-20 12:22:03.157 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mysql.persist'
2020-02-20 12:22:06.357 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2020-02-20 12:22:07.427 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.252.130:666
2020-02-20 12:22:07.434 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.252.130:6666
2020-02-20 12:22:08.183 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2020-02-20 12:22:08.279 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2020-02-20 12:22:08.390 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2020-02-20 12:22:10.158 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '192.168.252.130' with clientid fa2dc393-bc50-42dd-a3fd-194b42b3f74f
2020-02-20 12:22:11.278 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_BatteryLevel'.
2020-02-20 12:22:11.317 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'WindowSensor'.
2020-02-20 12:22:11.328 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'Test_Item_2'.
2020-02-20 12:22:11.333 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorLuminance'.
2020-02-20 12:22:11.341 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'Test_Item_1'.
2020-02-20 12:22:11.346 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_MotionAlarm'.
2020-02-20 12:22:11.368 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorTemperature'.
2020-02-20 12:22:15.264 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2020-02-20 12:22:21.665 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:22:21.667 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:23:19.045 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorTemperature'.
2020-02-20 12:24:28.472 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorTemperature'.
2020-02-20 12:26:22.862 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:26:22.864 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:26:49.611 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:26:49.613 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:27:02.121 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:27:02.122 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:40:20.354 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:40:20.360 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:46:55.188 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:46:55.189 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2020-02-20 12:47:46.916 [WARN ] [org.jline                           ] - The Parser of class org.apache.karaf.shell.impl.console.ConsoleSessionImpl$$Lambda$853/0x0000000100e71440 does not support the CompletingParsedLine interface. Completion with escaped or quoted words won't work correctly.
2020-02-20 12:47:48.309 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homie.internal.discovery.Homie300Discovery@6016138f from  discovery topic homeassistant/# on broker mqtt:broker:82cd2be3 but topic not registered for listener. Check discovery logic!
2020-02-20 12:47:48.359 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@7fb8519e from  discovery topic +/+/$homie on broker mqtt:broker:82cd2be3 but topic not registered at all. Check discovery logic!
2020-02-20 12:47:48.366 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@7fb8519e from  discovery topic homeassistant/# on broker mqtt:broker:82cd2be3 but topic not registered at all. Check discovery logic!
2020-02-20 12:47:48.368 [WARN ] [g.mqtt.handler.AbstractBrokerHandler] - Tried to unsubscribe org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@7fb8519e from  discovery topic +/+/$homie on broker mqtt:broker:82cd2be3 but topic not registered at all. Check discovery logic!
2020-02-20 12:47:49.668 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Stopped Paper UI
2020-02-20 12:47:49.692 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Stopped Home Builder
2020-02-20 12:47:49.706 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel
2020-02-20 12:47:49.760 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI
2020-02-20 12:47:49.821 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard
2020-02-20 12:48:32.754 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mysql.persist'
2020-02-20 12:48:36.017 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2020-02-20 12:48:36.879 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.252.130:666
2020-02-20 12:48:36.880 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.252.130:6666
2020-02-20 12:48:37.382 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2020-02-20 12:48:37.463 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2020-02-20 12:48:37.539 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2020-02-20 12:48:39.087 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '192.168.252.130' with clientid 6f16bcef-a82f-4aa9-b5db-c0a6ab8e286f
2020-02-20 12:48:40.154 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_BatteryLevel'.
2020-02-20 12:48:40.189 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'WindowSensor'.
2020-02-20 12:48:40.197 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'Test_Item_2'.
2020-02-20 12:48:40.204 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorLuminance'.
2020-02-20 12:48:40.214 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'Test_Item_1'.
2020-02-20 12:48:40.219 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_MotionAlarm'.
2020-02-20 12:48:40.239 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Unable to find table for query 'ZWaveNode002_SensorTemperature'.
2020-02-20 12:48:44.480 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2020-02-20 12:49:19.847 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2020-02-20 12:49:19.849 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.

I’m at a loss of what to do here, as i don’t quite understand what is going wrong. I would assume that for some reason there is no communication between the stick and the sensor, leading to no updates to any values/states however i’m also not entirely sure how i can test/verify/fix this? If it helps at all, my zstick when plugged in continually changes between green, red, and blue lights without any change whatsoever. I have tried investigating if these are the correct colours to expect but i cannot find anything from aotec on this either.

I have tried factory resetting both the sensor and the stick multiple times, deleting them from openhab and readding them, etc to no avail. Thank you for taking the time to read this.

2

Just a quick extra, i’m aware of the mqtt and mysql errors. They’re just there because while i’m testing this i have those services off so they won’t be able to connect

3

Did you upgrade from a previous version of openHAB or impost a configuration from an older version?

That looks like there is an invalid addon. The loader reloads all addons every minute trying to load the invalid one. Restdocs is a common culprit from old configurations since it moved from misc to ui.

Look at the addons.config file that is somewhere in the userdata tree. Stop openHAB before editing though.

4

I haven’t upgraded from any previous versions or imported any configurations. The contents of my addons.config are:

:org.apache.felix.configadmin.revision:=L"5"
binding="mqtt,zwave"
package="standard"
persistence="mysql"
service.pid="org.openhab.addons"
ui="dashboard,basic,paper,habpanel,homebuilder"

I will create a new virtual machine with a fresh install of openhab just to see if that will fix it, although i am not very hopeful as i am having the exact same problem on a fresh install on a raspberry pi 4 running raspbian.

1 Like
5

Update:

I have created a new ubuntu vm with a fresh install of openhab. This has given me the exact same results. The device can be added to the network and appears as a thing, however adding any items to the channels yields no data whatsoever.

6

Further update:

I have managed to get the logging to debug for the zwave network. The following is a log for when i make the device (ZWA005) send a NIF to the controller. Hoping this might be helpful, it doesn’t mean a lot to me right now

03:42:42.726 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:42:42.759 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:00.058 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:03.421 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:03.464 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:13.262 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:13.301 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:19.972 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:20.019 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:24.701 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 49 84 02 08 04 07 01 5E 98 9F 55 6C 54 
03:43:24.702 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=2, callback=132, payload=84 02 08 04 07 01 5E 98 9F 55 6C 
03:43:24.703 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=2, callback=132, payload=84 02 08 04 07 01 5E 98 9F 55 6C 
03:43:24.703 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
03:43:24.703 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
03:43:24.703 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
03:43:24.703 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=2, callback=132, payload=84 02 08 04 07 01 5E 98 9F 55 6C 
03:43:24.703 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 2: Application update request. Node information received. Transaction null
03:43:24.704 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Unsupported command class COMMAND_CLASS_SECURITY_2
03:43:24.704 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE
03:43:24.704 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Unsupported command class COMMAND_CLASS_SUPERVISION
03:43:24.704 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 2: Application update - no transaction.
03:43:24.704 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
03:43:24.705 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
03:43:24.955 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Is awake with 1 messages in the queue
03:43:24.956 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Start sleep timer at 5000ms
03:43:24.956 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveNodeStatusEvent
03:43:24.958 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zwave:device:d2b67f01:node2' has been updated.
03:43:24.966 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 2: Node Status event - Node is AWAKE
03:43:32.391 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:43:32.435 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:05.785 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:05.875 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:56.047 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:56.084 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:57.461 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:57.496 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:59.628 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
03:44:59.702 [INFO ] [pport.EventAdminConfigurationNotifier] - Sending Event Admin nofification (configuration successful) to org/ops4j/pax/logging/Configuration
7

First thing to do is meet the installation prerequisites:

Make sure that you have an up to date Java 8 platform installed on your host system.

1 Like
8

I have done a fresh reinstall of openhab on an ubuntu machine that has zulu java 8. I am still facing the same problem as before unfortunately

9

yeah I figured it wasn’t going to fix it but it is a requirement and it does sometime screw stuff up.
Anyhow is the stick supposed to do this?

Can any one who has an zstick5 confirm? That sounds a little wack-a-doodles to me.
You say latest version of OpenHAB but you are going to need to tell us exactly what version, latest would be snapshot or stable latest?
hate to say it but I think I’e heard a few people with this stick having problems with latest build

10

Openhab-cli tells me i’m using version 2.5.2, which i installed through command line using the instructions found at https://www.openhab.org/docs/installation/.

After playing around some more and forcing my logs to only show me zwave related things i now have the following:

13:16:52.756 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zwave:device:8a561d29:node2' has been updated.
13:16:52.763 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 2: Node Status event - Node is AWAKE
13:16:55.254 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: WakeupTimerTask 9 Messages waiting, state VERSION
13:16:57.754 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: WakeupTimerTask 9 Messages waiting, state VERSION
13:16:57.755 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: No more messages, go back to sleep
13:16:57.755 [DEBUG] [.commandclass.ZWaveWakeUpCommandClass] - NODE 2: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
13:16:57.756 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY NOT required on COMMAND_CLASS_WAKE_UP
13:16:57.756 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
13:16:57.756 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@7f889df
13:16:57.757 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Bump transaction 75 priority from Immediate to Immediate
13:16:57.758 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
13:16:57.758 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Added 75 to queue - size 10
13:16:57.759 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
13:16:57.760 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 86 13 70 25 26 01 
13:16:57.760 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 86 13 70 25 26 01 
13:16:57.840 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
13:16:57.840 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
13:16:57.840 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
13:16:57.841 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
13:16:57.841 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
13:16:57.841 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
13:17:01.965 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 26 01 01 9B 56 
13:17:01.969 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=38, payload=26 01 01 9B 
13:17:01.969 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=38, payload=26 01 01 9B 
13:17:01.971 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 60: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 38
13:17:01.971 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
13:17:01.971 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 60: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 38
13:17:01.972 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 60: (Callback 38)
13:17:01.972 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
13:17:01.972 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 60: callback 38
13:17:01.973 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=38, payload=26 01 01 9B 
13:17:01.973 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 38, Status = Transmission complete, no ACK received(1)
13:17:01.973 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 60: Transaction CANCELLED
13:17:01.979 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
13:17:01.979 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Transaction failed waiting for REQUEST, assume sleeping device.
13:17:01.980 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: CANCEL while sending message. Requeueing - 2 attempts left!
13:17:01.980 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 60: Transaction RESET with 2 retries remaining.
13:17:01.981 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
13:17:01.988 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: Added 60 to queue - size 10
13:17:01.989 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
13:17:01.990 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 2: TID 60: Transaction not completed
13:17:01.990 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
13:17:01.991 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
13:19:49.782 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...
13:19:49.786 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling deferred until initialisation complete

Getting the device to send a NIF yields:

13:49:36.080 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1C 00 49 84 02 16 04 07 01 5E 98 9F 55 86 73 85 8E 59 72 5A 80 84 30 71 31 70 6C 7A A9 
13:49:36.084 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=2, callback=132, payload=84 02 16 04 07 01 5E 98 9F 55 86 73 85 8E 59 72 5A 80 84 30 71 31 70 6C 7A 
13:49:36.085 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=2, callback=132, payload=84 02 16 04 07 01 5E 98 9F 55 86 73 85 8E 59 72 5A 80 84 30 71 31 70 6C 7A 
13:49:36.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
13:49:36.087 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
13:49:36.087 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
13:49:36.088 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=2, callback=132, payload=84 02 16 04 07 01 5E 98 9F 55 86 73 85 8E 59 72 5A 80 84 30 71 31 70 6C 7A 
13:49:36.088 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 2: Application update request. Node information received. Transaction null
13:49:36.089 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Unsupported command class COMMAND_CLASS_SECURITY_2
13:49:36.089 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE
13:49:36.090 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 2: Unsupported command class COMMAND_CLASS_SUPERVISION
13:49:36.090 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 2: Application update - no transaction.
13:49:36.091 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
13:49:36.091 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
13:49:36.344 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Is awake with 10 messages in the queue
13:49:36.344 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 2: Start sleep timer at 5000ms
13:49:36.345 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveNodeStatusEvent
13:49:36.349 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zwave:device:8a561d29:node2' has been updated.
13:49:36.359 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 2: Node Status event - Node is AWAKE

Which is strange as looking at advice for the node polling problem tells me to force the node to wake, however when i wake the node i get nothing showing up in the logs for it. Maybe someone more adept than i understands what is the problem here

11

I had that happen with one node where the manual said a single press of the bitton waked it up. It actually took 3 presses for the wake message to show.

12

Ok so i cleared the log to do some more testing and sent two NIFs in relatively quick succession by pressing the button on the device. The controller had a little led fit and then i now have the following log https://drive.google.com/open?id=1nnkpKx4JUhe3eyMm_3kJjuVCoFunT_W- (uploaded to gdrive as it’s too big for here)

What’s weird is that the device itself is actually processing motion events now, i.e. i can see the green LED telling me that it has detected motion, yet i am not receiving any information in openhab.

13

Nevermind

13:59:49.783 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...
13:59:49.783 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling deferred until initialisation complete

I got hopeful over nothing as it still can’t poll the device

14

I’m literally losing my mind. I decided on a whim to press the wake up notification again and now i am successfully getting data through (well, temp and luminance) to openhab. I have absolutely no idea what i did, and i have absolutely no idea how i fixed it. This is the log from the failed poll through to forcing it to wake, in case anyone can see anything https://drive.google.com/open?id=1oJpqWhshCod3luFttCMXxWb32tsmJJhX