Problems (Z-Wave) with #1134 Snapshot

maydo · December 14, 2017, 9:29am

Hi,

i have just upgraded from #1114 to #1134 snapshot release

my z-wave stick dont be seen in openhab, see screenshot.

i have tried readding the stick, same issue.

how to debug ?

running on ubuntu 17.04 as vm on virtualbox

sihui · December 14, 2017, 9:41am

Check via karaf console if the serial transport dependency is properly installed:

26 │ Active │ 80 │ 3.12.0.OH │ nrjavaserial

If okay, check via

dmesg -T | grep tty

if your port has changed …

maydo · December 14, 2017, 9:54am

25 │ Active │ 80 │ 3.12.0.OH │ nrjavaserial

is installed

[Thu Dec 14 10:48:33 2017] cdc_acm 1-1:1.0: ttyACM0: USB ACM device

also the port is ok.

any other ideas ?

i go almost thru all daily snapshots, and never had this issue

maydo · December 14, 2017, 10:10am

2017-12-14 10:58:19.658 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.zwave.handler.ZWaveSerialHandler@a25e301': null
java.lang.NullPointerException: null
	at org.openhab.binding.zwave.internal.ZWaveConfigProvider.getThingTypeConfig(ZWaveConfigProvider.java:398) [239:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.initialize(ZWaveControllerHandler.java:95) [239:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.initialize(ZWaveSerialHandler.java:78) [239:org.openhab.binding.zwave:2.2.0.201712132232]
	at sun.reflect.GeneratedMethodAccessor73.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [109:org.eclipse.smarthome.core:0.10.0.201712132200]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [109:org.eclipse.smarthome.core:0.10.0.201712132200]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2017-12-14 10:58:19.670 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occurred while initializing handler of thing 'zwave:serial_zstick:06074ec7': null
java.lang.NullPointerException: null
	at org.openhab.binding.zwave.internal.ZWaveConfigProvider.getThingTypeConfig(ZWaveConfigProvider.java:398) [239:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.initialize(ZWaveControllerHandler.java:95) [239:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.initialize(ZWaveSerialHandler.java:78) [239:org.openhab.binding.zwave:2.2.0.201712132232]
	at sun.reflect.GeneratedMethodAccessor73.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [109:org.eclipse.smarthome.core:0.10.0.201712132200]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [109:org.eclipse.smarthome.core:0.10.0.201712132200]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]

i just find these error logs

maydo · December 14, 2017, 10:43am

i just find these in paperui

seems to be double entry ?
and cant be changed (dropdown)

sihui · December 14, 2017, 12:04pm

Check if you have two zwave bindings installed (through karaf bundle:list).
If not it is maybe time to clear contents of cache and tmp directories …

maydo · December 14, 2017, 12:17pm

it is just one binding

bundle:list | grep Z
256 │ Active │ 80 │ 2.2.0.201712141125 │ ZWave Binding

updated to #1135
cleared tmp & cache folder

same error in logs

2017-12-14 13:13:22.140 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.zwave.handler.ZWaveSerialHandler@2f5e7ef8': null
java.lang.NullPointerException: null
	at org.openhab.binding.zwave.internal.ZWaveConfigProvider.getThingTypeConfig(ZWaveConfigProvider.java:398) [239:org.openhab.binding.zwave:2.2.0.201712141125]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.initialize(ZWaveControllerHandler.java:95) [239:org.openhab.binding.zwave:2.2.0.201712141125]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.initialize(ZWaveSerialHandler.java:78) [239:org.openhab.binding.zwave:2.2.0.201712141125]
	at sun.reflect.GeneratedMethodAccessor87.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [109:org.eclipse.smarthome.core:0.10.0.201712141004]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [109:org.eclipse.smarthome.core:0.10.0.201712141004]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]
2017-12-14 13:13:22.141 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occurred while initializing handler of thing 'zwave:serial_zstick:06074ec7': null
java.lang.NullPointerException: null
	at org.openhab.binding.zwave.internal.ZWaveConfigProvider.getThingTypeConfig(ZWaveConfigProvider.java:398) [239:org.openhab.binding.zwave:2.2.0.201712141125]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.initialize(ZWaveControllerHandler.java:95) [239:org.openhab.binding.zwave:2.2.0.201712141125]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.initialize(ZWaveSerialHandler.java:78) [239:org.openhab.binding.zwave:2.2.0.201712141125]
	at sun.reflect.GeneratedMethodAccessor87.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [109:org.eclipse.smarthome.core:0.10.0.201712141004]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [109:org.eclipse.smarthome.core:0.10.0.201712141004]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
	at java.lang.Thread.run(Thread.java:748) [?:?]

sihui · December 14, 2017, 12:32pm

Hmm, out of ideas, sorry

kubawolanin · December 14, 2017, 12:34pm

On #1135 and experiencing the same issue.

maydo · December 14, 2017, 12:57pm

i just tried downgrading to latest working snapshot #1114
but same issue

any ideas how to get work again ?

kubawolanin · December 14, 2017, 1:29pm

This post helped me a lot

This is what I did:

removed the content of /tmp/ and /cache/ in openhab2-userdata
Removed zwave binding from services/addons.cfg - if you installed it through Paper UI, uninstall it same way
Removed old Things and XMLs
logged into Karaf console (ssh openhab@localhost -p 8101)
bundle:install http://www.cd-jackson.com/downloads/openhab2/org.openhab.binding.zwave-2.2.0-SNAPSHOT.jar
bundle:list | grep Z to see what’s the ID of our new bundle
bundle:start 265

Now everything is back to normal.

maydo · December 14, 2017, 1:48pm

did you re add (bind) things or did they appear automatically ?

i have about 160 things, if they will be gone, i need days to reconfig them

Dim · December 14, 2017, 4:39pm

so, you switched to the dev/sec version of the Z-Wave binding (removed snapshot zwave) and it works ok on OH2 core Snapshot builds greater than 1134?

kubawolanin · December 14, 2017, 5:24pm

No, I’ve rolled back to #1120 and I’ve readded my Z-wave things.

Dim · December 14, 2017, 5:26pm

ok
by the way… the link that you referred to (http://www.cd-jackson.com/downloads/openhab2/org.openhab.binding.zwave-2.2.0-SNAPSHOT.jar ) is the dev/sec version from this thread: OH2 Z-Wave refactoring and testing... and SECURITY

that’s different than the “mainstream” snapshot version of the z-wave binding

So: You went back to S1120 OH2.2 core and you are using the dev/sec zwave… correct?

what version is the bundle that you are using?

kubawolanin · December 14, 2017, 5:34pm

openHAB 2.2.0~20171209104822-1 (Build #1120) on RPi3B running openHABian.

Yes, the bundle is from the security thread - that was the only one that worked for me, unlike the mainstream version.

what version is the bundle that you are using?

openhab> bundle:list | grep Z
265 │ Active   │  80 │ 2.2.0.201712100924     │ ZWave Binding

Dim · December 14, 2017, 11:37pm

solution has been provided by @chris: it’s included in Snapshot Build 1137

maydo · December 15, 2017, 10:41am

is fixed in #1137