Z-Wave Serial Controller bridge shows "ERROR: HANDLER" after Update to OH 3.3.0 M2

After I have updated from 3.2.0-1 to 3.3.0~M2 today, my Z-Wave serial controller is not beeing initialized and therefore all z–wave nodes remain offline.

Within the Thing configuration page it shows the status “UNINITIALIZED” and “HANDLER_CONFIGURATION_PENDING” similar as described this post
In openhab.log I only see the following messages:

[INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null

I’m running OH for years now (actually since v1) with the same hardware without issues. Typical troubleshooting steps performed for z-wave controller (Z-wave.me UZB stick) so far and everything is looking fine like before:

cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
crw-rw---- 1 root dialout 166, 0 Mar 3 12:51 /dev/ttyACM0

After downgrading to 3.2.0-1 everything is working fine again

I have seen an issue where the 3.3 version was validating the settings of one of my controllers and failing so the controller did not initialise. I was not sure if it was a bug as other controllers I tested worked. The fix was to edit and save the SUC id a few times. There was no error shown on the UI.

Does this sound like your issue?

not sure if this is the same issue. I have tested with my backup controller (similar hw model ZMEUZB) and had the same issue. There was actually no error shown on the UI, the bridge was just saying it’s in unitialized state.
I have no thing file for the controller, so I’m not sure how to edit the SUC id and test if this would solve my issue?

below the DEBUG logs of the zwave binding when starting openhab, maybe this provides further meaningful information

2022-03-03 12:09:22.406 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : BundleComponentActivator : ComponentHolder created.
2022-03-03 12:09:22.445 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2022-03-03 12:09:22.452 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ConfigDescriptionRegistryinterface=org.openhab.core.config.core.ConfigDescriptionRegistry, filter=null, policy=sta
tic, cardinality=1..1, bind=setConfigDescriptionRegistry, unbind=unsetConfigDescriptionRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-03-03 12:09:22.465 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ThingRegistryinterface=org.openhab.core.thing.ThingRegistry, filter=null, policy=static, cardinality=1..1, bind=se
tThingRegistry, unbind=unsetThingRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-03-03 12:09:22.467 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ThingTypeRegistryinterface=org.openhab.core.thing.type.ThingTypeRegistry, filter=null, policy=static, cardinality=
1..1, bind=setThingTypeRegistry, unbind=unsetThingTypeRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2022-03-03 12:09:22.470 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component created: DS=DS13, implementation=org.openhab.binding.zwave.internal.ZWaveConfigProvider, immediate=true, default-enabled=true, fact
ory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.zwave.internal.ZWaveConfigProvider]
2022-03-03 12:09:22.472 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component Services: scope=singleton, services=[org.openhab.core.config.core.ConfigDescriptionProvider, org.openhab.core.config.core.ConfigOpt
ionProvider]
2022-03-03 12:09:22.484 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component Properties: {}
2022-03-03 12:09:22.487 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
2022-03-03 12:09:22.489 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
2022-03-03 12:09:22.492 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component can not be activated since it is in state disabled
2022-03-03 12:09:22.512 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
2022-03-03 12:09:22.514 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Updating target filters
2022-03-03 12:09:22.517 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : No change in target property for dependency ConfigDescriptionRegistry: currently registered: false
2022-03-03 12:09:22.518 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] :  No existing service listener to unregister for dependency ConfigDescriptionRegistry
2022-03-03 12:09:22.521 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Setting target property for dependency ConfigDescriptionRegistry to null
2022-03-03 12:09:22.533 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : New service tracker for ConfigDescriptionRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab
.core.config.core.ConfigDescriptionRegistry), initialReferenceFilter (objectClass=org.openhab.core.config.core.ConfigDescriptionRegistry)
2022-03-03 12:09:22.538 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ConfigDescriptionRegistry tracker reset (closed)
2022-03-03 12:09:22.542 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic added {org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=243, ser
vice.bundleid=164, service.scope=bundle, component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=112} (enter)
2022-03-03 12:09:22.544 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-03-03 12:09:22.546 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic added {org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=243, ser
vice.bundleid=164, service.scope=bundle, component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=112} (exit)
2022-03-03 12:09:22.547 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ConfigDescriptionRegistry tracker opened
2022-03-03 12:09:22.549 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : registering service listener for dependency ConfigDescriptionRegistry
2022-03-03 12:09:22.550 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : No change in target property for dependency ThingRegistry: currently registered: false
2022-03-03 12:09:22.552 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] :  No existing service listener to unregister for dependency ThingRegistry
2022-03-03 12:09:22.574 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Setting target property for dependency ThingRegistry to null
2022-03-03 12:09:22.576 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : New service tracker for ThingRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.
ThingRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.ThingRegistry)
2022-03-03 12:09:22.578 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingRegistry tracker reset (closed)
2022-03-03 12:09:22.603 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingRegistry tracking 2 SingleStatic added {org.openhab.core.thing.ThingRegistry}={service.id=377, service.bundleid=213, service.sco
pe=bundle, component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=247} (enter)
2022-03-03 12:09:22.606 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingRegistry tracking 2 SingleStatic active: false trackerOpened: false optional: false
2022-03-03 12:09:22.609 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingRegistry tracking 2 SingleStatic added {org.openhab.core.thing.ThingRegistry}={service.id=377, service.bundleid=213, service.sco
pe=bundle, component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=247} (exit)
2022-03-03 12:09:22.611 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingRegistry tracker opened
2022-03-03 12:09:22.624 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : registering service listener for dependency ThingRegistry
2022-03-03 12:09:22.626 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : No change in target property for dependency ThingTypeRegistry: currently registered: false
2022-03-03 12:09:22.628 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] :  No existing service listener to unregister for dependency ThingTypeRegistry
2022-03-03 12:09:22.630 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Setting target property for dependency ThingTypeRegistry to null
2022-03-03 12:09:22.643 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : New service tracker for ThingTypeRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ThingTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ThingTypeRegistry)
2022-03-03 12:09:22.645 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingTypeRegistry tracker reset (closed)
2022-03-03 12:09:22.650 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingTypeRegistry tracking 3 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=213, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=262} (enter)
2022-03-03 12:09:22.663 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingTypeRegistry tracking 3 SingleStatic active: false trackerOpened: false optional: false
2022-03-03 12:09:22.666 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingTypeRegistry tracking 3 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=213, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=262} (exit)
2022-03-03 12:09:22.669 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : dm ThingTypeRegistry tracker opened
2022-03-03 12:09:22.671 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : registering service listener for dependency ThingTypeRegistry
2022-03-03 12:09:22.684 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Changed state from disabled to unsatisfiedReference
2022-03-03 12:09:22.686 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Component enabled
2022-03-03 12:09:22.688 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : ActivateInternal
2022-03-03 12:09:22.691 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Querying state unsatisfiedReference
2022-03-03 12:09:22.697 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Querying state unsatisfiedReference
2022-03-03 12:09:22.699 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Activating component from state unsatisfiedReference
2022-03-03 12:09:22.703 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Querying state unsatisfiedReference
2022-03-03 12:09:22.705 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Querying state unsatisfiedReference
2022-03-03 12:09:22.708 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Changed state from unsatisfiedReference to satisfied
2022-03-03 12:09:22.743 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : registration change queue [registered]
2022-03-03 12:09:22.776 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Checking constructor public org.openhab.binding.zwave.internal.ZWaveConfigProvider()
2022-03-03 12:09:22.778 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Found constructor with 0 arguments : public org.openhab.binding.zwave.internal.ZWaveConfigProvider()
2022-03-03 12:09:22.780 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getting bind: setConfigDescriptionRegistry
2022-03-03 12:09:22.783 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Locating method setConfigDescriptionRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:22.815 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry
2022-03-03 12:09:22.824 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry([interface org.osgi.framework.ServiceReference]) not found
2022-03-03 12:09:22.826 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-03-03 12:09:22.828 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getReferenceClass: Looking for interface class org.openhab.core.config.core.ConfigDescriptionRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:22.830 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getParameterClass: Found class org.openhab.core.config.core.ConfigDescriptionRegistry
2022-03-03 12:09:22.832 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.config.core.ConfigDescriptionRegistry
2022-03-03 12:09:22.879 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry(org.openhab.core.config.core.ConfigDescriptionRegistry)
2022-03-03 12:09:22.885 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry(org.openhab.core.config.core.ConfigDescriptionRegistry)
2022-03-03 12:09:22.888 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getting bind: setThingRegistry
2022-03-03 12:09:22.890 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Locating method setThingRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:22.891 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry
2022-03-03 12:09:22.894 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry([interface org.osgi.framework.ServiceReference]) not found
2022-03-03 12:09:22.896 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-03-03 12:09:22.898 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getReferenceClass: Looking for interface class org.openhab.core.thing.ThingRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:22.901 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getParameterClass: Found class org.openhab.core.thing.ThingRegistry
2022-03-03 12:09:22.903 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.thing.ThingRegistry
2022-03-03 12:09:22.920 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry(org.openhab.core.thing
2022-03-03 12:09:22.922 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry(org.openhab.core.thing.ThingRegistry)
2022-03-03 12:09:22.948 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getting bind: setThingTypeRegistry
2022-03-03 12:09:23.024 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Locating method setThingTypeRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:23.040 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry
2022-03-03 12:09:23.045 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry([interface org.osgi.framework.ServiceReference]) not found
2022-03-03 12:09:23.048 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-03-03 12:09:23.050 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getReferenceClass: Looking for interface class org.openhab.core.thing.type.ThingTypeRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:23.052 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getParameterClass: Found class org.openhab.core.thing.type.ThingTypeRegistry
2022-03-03 12:09:23.193 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.thing.type.ThingTypeRegistry
2022-03-03 12:09:23.197 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry(org.openhab.core.thing.type.ThingTypeRegistry)
2022-03-03 12:09:23.200 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry(org.openhab.core.thing.type.ThingTypeRegistry)
2022-03-03 12:09:23.202 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : This thread collected dependencies
2022-03-03 12:09:23.261 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getService (single component manager) dependencies collected.
2022-03-03 12:09:23.263 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Querying state satisfied
2022-03-03 12:09:23.265 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Querying state satisfied
2022-03-03 12:09:23.268 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : For dependency ConfigDescriptionRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=243, service.bundleid=164, service.scope=bundle, component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=112}] service: [org.openhab.core.config.core.ConfigDescriptionRegistry@b7e11]]]
2022-03-03 12:09:23.270 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : For dependency ThingRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.ThingRegistry}={service.id=377, service.bundleid=213, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=247}] service: [org.openhab.core.thing.internal.ThingRegistryImpl@1810c1a]]]
2022-03-03 12:09:23.272 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : For dependency ThingTypeRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=213, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=262}] service: [org.openhab.core.thing.type.ThingTypeRegistry@a13079]]]
2022-03-03 12:09:23.787 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : invoking bind: setConfigDescriptionRegistry: parameters [org.openhab.core.config.core.ConfigDescriptionRegistry]
2022-03-03 12:09:23.789 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : invoked bind: setConfigDescriptionRegistry
2022-03-03 12:09:23.791 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : invoking bind: setThingRegistry: parameters [org.openhab.core.thing.internal.ThingRegistryImpl]
2022-03-03 12:09:23.792 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : invoked bind: setThingRegistry
2022-03-03 12:09:23.794 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : invoking bind: setThingTypeRegistry: parameters [org.openhab.core.thing.type.ThingTypeRegistry]
2022-03-03 12:09:23.796 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : invoked bind: setThingTypeRegistry
2022-03-03 12:09:23.798 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : getting activate: activate
2022-03-03 12:09:23.800 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Locating method activate in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2022-03-03 12:09:23.802 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-03-03 12:09:23.816 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Locating method activate in class java.lang.Object
2022-03-03 12:09:23.818 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-03-03 12:09:23.820 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : activate method [activate] not found, ignoring
2022-03-03 12:09:23.835 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Set implementation object for component
2022-03-03 12:09:23.837 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.3.0.M2 (271)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(295)] : Changed state from satisfied to active
2022-03-03 12:09:37.533 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:5045e1bf with scan time of 60
2022-03-03 12:09:37.535 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:5045e1bf
2022-03-03 12:09:37.537 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2022-03-03 12:10:03.468 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:5045e1bf
2022-03-03 12:10:30.803 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:5045e1bf
2022-03-03 12:11:45.679 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:5045e1bf

I can not reproduce so it is hard to say.

It is the SIS node setting under advanced settings that was the issue with the controller that had an error. It was blank rather than set to my controller id.

When setting it to 1 as it should be it behaved strangely and took a few saves.

I think there were changes to the configuration validation recently and it certainly caused issues for slave device configuration so possibly connected.

thanks, but I have double checked and the SIS was already set to 1

any idea what I should do next? or any other debug logs required for further troubleshooting?

I found the config issues with setting the whole openhab to DEBUG and than tried to safe the config in the UI.
See also the thread about the milestone release

Confirm the same result on upgrade to 3.3.0~M2 and return to successful operation on downgrading back to 3.2.0-1

Oddly not all the devices went offline. Mainly the nano switches.

As noted by @usambara there is a “strict validation” in 3.3M2. I had a device with the HANDLER issue and needed to update the Zwave DB. Here is how I found it. I’m not sure this is the problem if it is the controller, but if only a few devices are not working, it is more likely.

Bob

I think I have the same issue with a Razberry module.

This is shown for my controller:

image

It seems there is no clear understanding yet why that is?

Any pointers how to do a downgrade correctly?

Wolfgang

as described in the Openhab documentation

Once you know which version you want, you can upgrade/downgrade to it by using the apt-get install openhab=[version] command, for example:

sudo apt-get install openhab=3.2.0-1

My only other thought here is that the controller might be caught up with this device validation issue, but I’m not sure what would be causing the problem. Can you post the UI Controller code page? This is mine and it works on 3.3M2. (Ignore the maxbatterywakeduration: that is my own fix for “One and DONE” battery device initialization).
Controller

Bob

sure, here is the output (actually before downgrade - but I assume it has not changed by OH version)

UID: zwave:serial_zstick:5045e1bf
label: Z-Wave Serial Controller
thingTypeUID: zwave:serial_zstick
configuration:
  controller_softreset: 0
  security_networkkey: xxxx
  security_inclusionmode: 0
  controller_suc: false
  controller_sisnode: 1
  controller_sync: 0
  controller_master: true
  inclusion_mode: 2
  port: /dev/ttyACM0
  controller_wakeupperiod: 3600
  controller_exclude: false
  heal_time: 2
  controller_inclusiontimeout: 30
  controller_hardreset: 0

I had no chance yet to update again and enable debug log:set Debug org.openhab.core
like you mentioned in your post

Downgraded to 3.3.0M1 and still failing the same way.
So 3.2.0-1 next I suppose.

maybe it’s related to the following attributes which should be boolean instead?

 controller_softreset: 0
 controller_sync: 0
 controller_hardreset: 0

I will move the thing configuration to config file and try to change the parameters

That is what I was thinking too. The XML file for the controller in the binding is this and it says the parameters are boolean, so maybe the validator is looking at that.
controller_serial.xml (12.7 KB)

Bob

PS: Should have blocked the security key, but I don’t use it anyway :wink:

1 Like

ok, will give it another try once my wife is “out-of-office” :wink:

I guess my zwave stick is one of the few remaining devices which is not yet configured via .thing file - because it is there via GUI and working since I started with Openhab with version 1.8 :slightly_smiling_face:

p.s.: Me neither - but somehow paranoid regarding security stuff

I have updated to OH3 3.3.0 M2 again and after enabling debug log:set Debug org.openhab.core I can confirm, that the issue with my ZWave controller is related to the device validation issue (although it’s not showing which config parameter is wrong)

2022-03-11 10:14:06.671 [DEBUG] [core.thing.internal.ThingManagerImpl] - Thing 'zwave:serial_zstick:5045e1bf' not initializable, check if required configuration par
ameters are present and set values are valid.

after “fixing” the wrong configuration parameters (actually I have migrated from UI to .thing configuration and just removed those 3 parameters) my ZWave controller thing came back online - and all Zwave Nodes as well!

Below my actual .thing configuration

Bridge zwave:serial_zstick:5045e1bf "ZWave Controller ZMEUZB" [ port="/dev/ttyACM0", controller_master=true, heal_enable=true, security_networkkey="xxx" ]

and corresponding UI controller code page:

UID: zwave:serial_zstick:5045e1bf
label: ZWave Controller ZMEUZB
thingTypeUID: zwave:serial_zstick
configuration:
  controller_softreset: false
  security_networkkey: xxx
  security_inclusionmode: 0
  controller_sisnode: 1
  heal_enable: true
  controller_sync: false
  port: /dev/ttyACM0
  controller_master: true
  inclusion_mode: 2
  controller_wakeupperiod: 3600
  heal_time: 2
  controller_exclude: false
  controller_inclusiontimeout: 30
  controller_hardreset: false

thanks a lot Bob for pointing me in the right direction!

as an additional hint:

ZWave manual thing configuration

@chris : Due to the the more stringent config validation in OH3 M1 the example is not working any longer. The boolean config parameters should look like controller_softreset=false instead of controller_softreset="false"

Thanks for help. Is there any way to solve the validation problem? My devices show online in OH3.3.0, when I try to change the item, thing goes offline with Communication_Error - Node is not communicating with controller. When I downgraded back to 3.2.0, it didn´t help.