OH2.2 S1133 & ZWave Error

just upgraded my OH 2.2 Snapshot to Build #1133 (with 2.2.0.201712121107 org.openhab.binding.zwave) and I am getting the following error in openhab.log:

==> openhab.log <==
2017-12-13 20:54:30.190 [ERROR] [ig.xml.osgi.XmlDocumentBundleTracker] - Could not send adding finished event for the module 'org.openhab.binding.zwave'!
java.lang.IllegalArgumentException: UID must have at least 2 segments.
	at org.eclipse.smarthome.core.thing.UID.<init>(UID.java:70) [119:org.eclipse.smarthome.core.thing:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.UID.<init>(UID.java:48) [119:org.eclipse.smarthome.core.thing:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.type.ChannelTypeUID.<init>(ChannelTypeUID.java:40) [119:org.eclipse.smarthome.core.thing:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.xml.internal.ChannelXmlResult.toChannelDefinition(ChannelXmlResult.java:121) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlResult.toChannelDefinitions(ThingTypeXmlResult.java:96) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlResult.getBuilder(ThingTypeXmlResult.java:148) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlResult.toThingType(ThingTypeXmlResult.java:156) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlProvider.addingFinished(ThingTypeXmlProvider.java:151) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.addingFinished(XmlDocumentBundleTracker.java:218) [111:org.eclipse.smarthome.config.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.parseDocuments(XmlDocumentBundleTracker.java:358) [111:org.eclipse.smarthome.config.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.processBundle(XmlDocumentBundleTracker.java:336) [111:org.eclipse.smarthome.config.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.access$3(XmlDocumentBundleTracker.java:331) [111:org.eclipse.smarthome.config.xml:0.10.0.201712111157]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker$2.run(XmlDocumentBundleTracker.java:307) [111:org.eclipse.smarthome.config.xml:0.10.0.201712111157]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
	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-13 20:54:30.410 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Connecting to serial port '/dev/ttyACM0'
2017-12-13 20:54:30.432 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
2017-12-13 20:54:30.440 [INFO ] [mmandclass.ZWaveSecurityCommandClass] - Update networkKey
2017-12-13 20:54:30.450 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2017-12-13 20:54:30.451 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2017-12-13 20:54:31.139 [INFO ] [ternal.scheduler.WeatherJobScheduler] - Starting and scheduling weatherJob-HomeR with interval of 10 minutes
2017-12-13 20:54:33.590 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 1: Node found
2017-12-13 20:54:33.590 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 2: Node found
2017-12-13 20:54:33.591 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 3: Node found
2017-12-13 20:54:33.591 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 4: Node found
2017-12-13 20:54:33.591 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 6: Node found
2017-12-13 20:54:33.591 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 8: Node found
2017-12-13 20:54:33.592 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 9: Node found
2017-12-13 20:54:33.592 [INFO ] [age.SerialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2017-12-13 20:54:33.592 [INFO ] [age.SerialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2017-12-13 20:54:33.592 [INFO ] [age.SerialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller------------
2017-12-13 20:54:33.593 [INFO ] [age.SerialApiGetInitDataMessageClass] - # Nodes = 7
2017-12-13 20:54:33.593 [INFO ] [age.SerialApiGetInitDataMessageClass] - ----------------------------------------------------------------------------

==> events.log <==
2017-12-13 20:54:34.286 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ZW090C:node8' has been updated.

I haven’t performed any troubleshooting so far (work in progress)… just wondering if anyone else is seeing the same…

Binding seems to work fine even with this error… I can control the Z-Wave devices.

similar case (IllegalArgumentException: UID must have at least 2 segments) was reported here: [Solved] NPEs in zwave binding with latest sanpshot build 2.1.0.201704151452

Hello, I can confirm the same error.
I am using Raspberry Pi 3
Linux raspberrypi 4.9.59-v7+ #1047 SMP Sun Oct 29 12:19:23 GMT 2017 armv7l GNU/Linux
Oracle JDK - java version “1.8.0_151”

Clean instalation of OS + clean instalation of OH2 following the instructions for apt repository here:
installation guide

After installing the binding I have received the same error and after system restart too.

2017-12-13 15:49:41.604 [ERROR] [ig.xml.osgi.XmlDocumentBundleTracker] - Could not send adding finished event for the module 'org.openhab.binding.zwave'!
java.lang.IllegalArgumentException: UID must have at least 2 segments.
	at org.eclipse.smarthome.core.thing.UID.<init>(UID.java:70) [114:org.eclipse.smarthome.core.thing:0.10.0.201712111157]

I’m seeing it, as well. Fresh, scratch install of the latest OpenHAB 2.2 snapshot a couple hours ago. My binding isn’t working, but I have yet to actually get anything Z-wave working with OpenHAB, so that isn’t necessarily a step backwards. (I doubt its related, but I can’t get any devices to control with any of the two controllers I’ve tried – a USB stick and a Razberry.) I’d done the reinstall to see if there was any difference in getting secure inclusion working (there hasn’t been). I think that’s the crux of the control problem I’ve got – secure inclusion just simply isn’t working.

I need to roll everything back to 2.1 and see if it works…

+1 same issue after fresh install of 2.2 snapshot

upgraded from S1133 to S1134 and still seeing the same issue
now, I also have a NPE which breaks Z-Wave :frowning:

2017-12-14 05:54:02.011 [ERROR] [ig.xml.osgi.XmlDocumentBundleTracker] - Could not send adding finished event for the module 'org.openhab.binding.zwave'!
java.lang.IllegalArgumentException: UID must have at least 2 segments.
	at org.eclipse.smarthome.core.thing.UID.<init>(UID.java:70) [119:org.eclipse.smarthome.core.thing:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.UID.<init>(UID.java:48) [119:org.eclipse.smarthome.core.thing:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.type.ChannelTypeUID.<init>(ChannelTypeUID.java:40) [119:org.eclipse.smarthome.core.thing:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.xml.internal.ChannelXmlResult.toChannelDefinition(ChannelXmlResult.java:121) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlResult.toChannelDefinitions(ThingTypeXmlResult.java:96) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlResult.getBuilder(ThingTypeXmlResult.java:148) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlResult.toThingType(ThingTypeXmlResult.java:156) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.core.thing.xml.internal.ThingTypeXmlProvider.addingFinished(ThingTypeXmlProvider.java:151) [120:org.eclipse.smarthome.core.thing.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.addingFinished(XmlDocumentBundleTracker.java:218) [111:org.eclipse.smarthome.config.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.parseDocuments(XmlDocumentBundleTracker.java:358) [111:org.eclipse.smarthome.config.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.processBundle(XmlDocumentBundleTracker.java:336) [111:org.eclipse.smarthome.config.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker.access$3(XmlDocumentBundleTracker.java:331) [111:org.eclipse.smarthome.config.xml:0.10.0.201712132200]
	at org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker$2.run(XmlDocumentBundleTracker.java:307) [111:org.eclipse.smarthome.config.xml:0.10.0.201712132200]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
	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 05:54:02.104 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.zwave.handler.ZWaveSerialHandler@34225da': null
java.lang.NullPointerException: null
	at org.openhab.binding.zwave.internal.ZWaveConfigProvider.getThingTypeConfig(ZWaveConfigProvider.java:398) [229:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.initialize(ZWaveControllerHandler.java:95) [229:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.initialize(ZWaveSerialHandler.java:78) [229:org.openhab.binding.zwave:2.2.0.201712132232]
	at sun.reflect.GeneratedMethodAccessor55.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) [112:org.eclipse.smarthome.core:0.10.0.201712132200]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [112: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 05:54:02.106 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occurred while initializing handler of thing 'zwave:serial_zstick:ZW090C': null
java.lang.NullPointerException: null
	at org.openhab.binding.zwave.internal.ZWaveConfigProvider.getThingTypeConfig(ZWaveConfigProvider.java:398) [229:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.initialize(ZWaveControllerHandler.java:95) [229:org.openhab.binding.zwave:2.2.0.201712132232]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler.initialize(ZWaveSerialHandler.java:78) [229:org.openhab.binding.zwave:2.2.0.201712132232]
	at sun.reflect.GeneratedMethodAccessor55.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) [112:org.eclipse.smarthome.core:0.10.0.201712132200]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [112: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 will roll back to an earlier snapshot while we wait for a fix from @chris :slight_smile:

this is more complex than I expected… I did downgrade the core to an earlier snapshot but the Z-Wave binding stays at the same (newest) version so I get the same errors.

I would have to uninstall the online repo bundle and deploy manually an earlier version of the binding… so I will go forward to S1134 and wait for a newer Z-Wave binding :slight_smile:

Same issue here, completely broken zwave as well.

just tried S1136… same story (both errors are still there)

Yea, I did the same. I started an issue on git. Christmas will need to be cancelled, I have no way to turn on my Christmas lights, haha…

1 Like

Looks like Kai found the problem :

Just waiting on Chris to get it fixed and and to have it pushed out.

Same here.
openHAB 2.2.0~20171214033329-1 (Build #1134)
[ERROR] [st.core.internal.thing.ThingResource] - Exception during HTTP PUT request for update config at 'things/zwave:serial_zstick…

openHAB 2.2.0~20171214152136-1 (Build #1136)
still broken

I’ve just created a PR for this and will kick off a build shortly. Please let me know if it is fixed a I’m not really able to test at the moment.

3 Likes

Thanks Chris! Here’s hoping it works, and you’ll officially save Christmas (lights)! :slight_smile:

zwave binding OK with
openHAB 2.2.0~20171214210842-1 (Build #1137)

Potentially a stupid question, but how all these github projects get merged together into the final distribution isn’t super clear… the openhab snapshot would need to be rebuilt as well to pick up a new snapshot build of the z-wave binding, correct? Or does the new snapshot build of the zwave binding automagically show up?

I’ve never seen an indication in PaperUI that a binding has an update available, and they’re not installed via dpkg, so I’m not sure how installed bindings get updated as opposed to manually copied ones…

As above, confirmed working with 1137. Thanks for the quick turnaround Chris, as always it’s appreciated!

2 Likes

Thanks @bhomeyer and @Gargam3L0 for the confirmation :slight_smile:

No - when installing from PaperUI, it will pick up the latest version.

It doesn’t really just show up, but if you uninstall the current version, wait 30 seconds and reinstall, it will pick up the latest snapshot. It resolves the version from the runtime, so if you’re on 2.1 release, you will always get the same version. IF you’re on 2.2-SNAPSHOT, then you always get the snapshot version which updates periodically…

Can also confirm that snapshot snapshot 1137 solved the issue.
@Chris: Thanks for the quick solution :slight_smile:

2 Likes

confirming also that all is good with S1137
thanx @chris !

Confirming as well… Thank you for the quick response and fix