Zwave items no longer updated

Euphi · November 18, 2016, 7:21pm

Since some days, my Zwave-Items are no longer updated, even if new messages are received in the binding (I activated DEBUG for the zwave logger and changes arrive correct and seems to be processed). However, there is no update for the linked item. I even tried to remove a link/item and recreate it, it still does not work.

I also restarted OpenHAB several times (and the computer one time) - still no update.

It happens for two device (no more connected).

In PaperUI, both Nodes (4 and 6, both are Fibaro Smoke Detectors) are shown as “Offline”.

In DEBUG log I see:

20:14:06.185 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 04 06 31 05 01 22 00 CD 2F 
20:14:06.198 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
20:14:06.205 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 04 06 31 05 01 22 00 CD 2F 
20:14:06.213 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 04 06 31 05 01 22 00 CD 2F 
20:14:06.227 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 06 31 05 01 22 00 CD 
20:14:06.231 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Application Command Request (ALIVE:DONE)
20:14:06.234 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 4: Starting initialisation from DONE
20:14:06.238 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@11be3a8 already registered
20:14:06.241 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Incoming command class SENSOR_MULTILEVEL
20:14:06.245 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
20:14:06.248 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Multi Level REPORT received
20:14:06.251 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Type = Temperature(1), Scale = 0
20:14:06.254 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 4: Sensor Value = 20.5
20:14:06.257 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
20:14:06.261 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=4, callback=47, payload=04 02 84 08 
20:14:06.267 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 06 31 05 01 22 00 CD 
20:14:06.270 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=47, expected=SendData, cancelled=false      MISMATCH

Is the “MISMATCH” in the last line ususal or it is an error?
Can I activate more DEBUG output in other loggers?

What does “already registered” mean in the log message from 20:14:06.238?

Little bit later I receive a message from Node 6:

20:16:35.715 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 06 06 31 05 01 22 00 CB 2B 
20:16:35.727 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
20:16:35.733 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 06 06 31 05 01 22 00 CB 2B 
20:16:35.742 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 06 06 31 05 01 22 00 CB 2B 
20:16:35.750 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 06 06 31 05 01 22 00 CB 
20:16:35.755 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DETAILS)
20:16:35.759 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class SENSOR_MULTILEVEL
20:16:35.763 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
20:16:35.766 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Multi Level REPORT received
20:16:35.769 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Type = Temperature(1), Scale = 0
20:16:35.773 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Value = 20.3
20:16:35.776 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
20:16:35.779 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Transaction not completed: node address inconsistent.  lastSent=4, incoming=255

Configured things:

zwave:device:4032118f:node4 (Type=Thing, Status=OFFLINE, Label=Z-Wave Node 4: Ian, Bridge=zwave:serial_zstick:4032118f)
zwave:device:4032118f:node6 (Type=Thing, Status=OFFLINE, Label=Z-Wave Node 6: Treppe, Bridge=zwave:serial_zstick:4032118f)
zwave:serial_zstick:4032118f (Type=Bridge, Status=ONLINE, Label=Z-Wave Serial Controller, Bridge=null)

Configured links:

ZWave_Ctrl_Bytes -> zwave:serial_zstick:4032118f:serial_oof
ZWave_Fire_Ian_Smoke -> zwave:device:4032118f:node4:alarm_smoke
ZWave_Fire_Ian_Tamper -> zwave:device:4032118f:node4:alarm_general
ZWave_Fire_Ian_Temperature -> zwave:device:4032118f:node4:sensor_temperature
ZWave_Fire_Treppe_Battery -> zwave:device:4032118f:node6:battery-level
ZWave_Fire_Treppe_Smoke -> zwave:device:4032118f:node6:alarm_smoke
ZWave_Fire_Treppe_Tamper -> zwave:device:4032118f:node6:alarm_general
ZWave_Fire_Treppe_Temperature -> zwave:device:4032118f:node6:sensor_temperature

Note: I created the item and link ZWave_Ctrl_Bytes -> zwave:serial_zstick:4032118f:serial_oof after the problem occured to see if this item gets updated --> it is NOT updated, too.

(@chris)

Euphi · November 18, 2016, 8:56pm

The red and green line show the temperature of the Z-Wave Smoke Detectors. You see, updates stopped working on Wednesday, then suddenly started to be updated again and since Thursday afternoon they are “stuck”.
(Pink and blue are MQTT sensors and they work fine (blue was switched off till Thursday).

Euphi · November 18, 2016, 9:17pm

I found some log entry at the time, where the Items stopped to be updated:


2016-11-17 16:21:40.622 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.627 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.633 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.638 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.647 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zwave:device:4032118f:node4': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
        at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:143)
2016-11-17 16:21:40.802 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zwave:device:4032118f:node6': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
        at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:143)

What happened? And why does the binding does not recover from this problem even after restart/reboot?

Log messages after restart:

2016-11-17 16:21:40.622 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.627 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.633 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.638 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.647 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zwave:device:4032118f:node4': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
       at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:143)
2016-11-17 16:21:40.802 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zwave:device:4032118f:node6': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
       at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:143)[/details]

chris · November 18, 2016, 11:47pm

It’s normal - this is related to transactions and doesn’t impact on the processing of data.

I’m afraid that I can’t really see anything wrong. The data is being received in the binding, and it’s being sent to the event listeners. I would tend to think this is something fundamental such as the system not reading the items file or something like that, rather than an error in the zwave side.

Euphi · November 19, 2016, 12:04am

I just had a look at the log files - other problems occured at the same time (especially with the Caldav binding), but these problem were resolved by a restart.

2016-11-17 16:21:40.622 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.627 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.633 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.638 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - Controller handler not found. Cannot handle command without ZWave controller.
2016-11-17 16:21:40.647 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zwave:device:4032118f:node4': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thin
g/binding/BridgeHandler;
java.util.concurrent.ExecutionException: java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
        at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_77]
        at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_77]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:186)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:79)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:63)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9.run(ThingManager.java:761)[103:org.eclipse.smarthome.core.thing:0.9.0.201611102032]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_77]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_77]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_77]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_77]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_77]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_77]
        at java.lang.Thread.run(Thread.java:745)[:1.8.0_77]
Caused by: java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
        at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:143)
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:764)
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:1)
        at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:177)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_77]
        ... 3 more
2016-11-17 16:21:40.802 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zwave:device:4032118f:node6': java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
java.util.concurrent.ExecutionException: java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
        at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_77]
        at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_77]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:186)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:79)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:63)[96:org.eclipse.smarthome.core:0.9.0.201611102032]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9.run(ThingManager.java:761)[103:org.eclipse.smarthome.core.thing:0.9.0.201611102032]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_77]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_77]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_77]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_77]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_77]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_77]
        at java.lang.Thread.run(Thread.java:745)[:1.8.0_77]
Caused by: java.lang.NoSuchMethodError: org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
        at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:143)
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:764)
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:1)
        at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:177)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_77]
        ... 3 more
2016-11-17 16:23:30.092 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events for config: owncloud
2016-11-17 16:23:31.331 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /remote.php/dav/calendars/ian/openhab/1479076613.R182.ics
2016-11-17 16:23:31.609 [ERROR] [org.quartz.core.JobRunShell         ] - Job event-reloader.owncloud threw an unhandled Exception: 
java.util.ServiceConfigurationError: net.fortuna.ical4j.model.PropertyFactory: Provider net.fortuna.ical4j.model.property.Action$Factory not a subtype
        at java.util.ServiceLoader.fail(ServiceLoader.java:239)[:1.8.0_77]
        at java.util.ServiceLoader.access$300(ServiceLoader.java:185)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:404)[:1.8.0_77]
        at java.util.ServiceLoader$1.next(ServiceLoader.java:480)[:1.8.0_77]
        at net.fortuna.ical4j.model.AbstractContentFactory.getFactory(AbstractContentFactory.java:85)
        at net.fortuna.ical4j.model.PropertyFactoryImpl.createProperty(PropertyFactoryImpl.java:78)
        at net.fortuna.ical4j.data.CalendarBuilder$ContentHandlerImpl.startProperty(CalendarBuilder.java:326)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.parse(CalendarParserImpl.java:206)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.access$1100(CalendarParserImpl.java:190)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyListParser.parse(CalendarParserImpl.java:173)
        at net.fortuna.ical4j.data.CalendarParserImpl.parse(CalendarParserImpl.java:122)
        at net.fortuna.ical4j.data.CalendarBuilder.build(CalendarBuilder.java:198)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:279)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:262)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.execute(EventReloaderJob.java:112)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
2016-11-17 16:23:31.710 [ERROR] [org.quartz.core.ErrorLogger         ] - Job (event-reloader.owncloud threw an exception.
org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.util.ServiceConfigurationError: net.fortuna.ical4j.model.PropertyFactory: Provider net.fortuna.ical4j.model.property.Action$Factory not a subtype]
        at org.quartz.core.JobRunShell.run(JobRunShell.java:213)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
Caused by: java.util.ServiceConfigurationError: net.fortuna.ical4j.model.PropertyFactory: Provider net.fortuna.ical4j.model.property.Action$Factory not a subtype
        at java.util.ServiceLoader.fail(ServiceLoader.java:239)[:1.8.0_77]
        at java.util.ServiceLoader.access$300(ServiceLoader.java:185)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:404)[:1.8.0_77]
        at java.util.ServiceLoader$1.next(ServiceLoader.java:480)[:1.8.0_77]
        at net.fortuna.ical4j.model.AbstractContentFactory.getFactory(AbstractContentFactory.java:85)
        at net.fortuna.ical4j.model.PropertyFactoryImpl.createProperty(PropertyFactoryImpl.java:78)
        at net.fortuna.ical4j.data.CalendarBuilder$ContentHandlerImpl.startProperty(CalendarBuilder.java:326)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.parse(CalendarParserImpl.java:206)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.access$1100(CalendarParserImpl.java:190)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyListParser.parse(CalendarParserImpl.java:173)
        at net.fortuna.ical4j.data.CalendarParserImpl.parse(CalendarParserImpl.java:122)
        at net.fortuna.ical4j.data.CalendarBuilder.build(CalendarBuilder.java:198)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:279)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:262)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.execute(EventReloaderJob.java:112)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
        ... 1 more
2016-11-17 16:25:30.094 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events for config: owncloud
2016-11-17 16:25:31.526 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /remote.php/dav/calendars/ian/openhab/1479076613.R182.ics
2016-11-17 16:25:31.857 [ERROR] [org.quartz.core.JobRunShell         ] - Job event-reloader.owncloud threw an unhandled Exception: 
java.util.ServiceConfigurationError: net.fortuna.ical4j.model.PropertyFactory: Provider net.fortuna.ical4j.model.property.Action$Factory not a subtype
        at java.util.ServiceLoader.fail(ServiceLoader.java:239)[:1.8.0_77]
        at java.util.ServiceLoader.access$300(ServiceLoader.java:185)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:404)[:1.8.0_77]
        at java.util.ServiceLoader$1.next(ServiceLoader.java:480)[:1.8.0_77]
        at net.fortuna.ical4j.model.AbstractContentFactory.getFactory(AbstractContentFactory.java:85)
        at net.fortuna.ical4j.model.PropertyFactoryImpl.createProperty(PropertyFactoryImpl.java:78)
        at net.fortuna.ical4j.data.CalendarBuilder$ContentHandlerImpl.startProperty(CalendarBuilder.java:326)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.parse(CalendarParserImpl.java:206)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.access$1100(CalendarParserImpl.java:190)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyListParser.parse(CalendarParserImpl.java:173)
        at net.fortuna.ical4j.data.CalendarParserImpl.parse(CalendarParserImpl.java:122)
        at net.fortuna.ical4j.data.CalendarBuilder.build(CalendarBuilder.java:198)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:279)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:262)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.execute(EventReloaderJob.java:112)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
2016-11-17 16:25:31.947 [ERROR] [org.quartz.core.ErrorLogger         ] - Job (event-reloader.owncloud threw an exception.
org.quartz.SchedulerException: Job threw an unhandled exception. [See nested exception: java.util.ServiceConfigurationError: net.fortuna.ical4j.model.PropertyFactory: Provider net.fortuna.ical4j.model.property.Action$Factory not a subtype]
        at org.quartz.core.JobRunShell.run(JobRunShell.java:213)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
Caused by: java.util.ServiceConfigurationError: net.fortuna.ical4j.model.PropertyFactory: Provider net.fortuna.ical4j.model.property.Action$Factory not a subtype
        at java.util.ServiceLoader.fail(ServiceLoader.java:239)[:1.8.0_77]
        at java.util.ServiceLoader.access$300(ServiceLoader.java:185)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376)[:1.8.0_77]
        at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:404)[:1.8.0_77]
        at java.util.ServiceLoader$1.next(ServiceLoader.java:480)[:1.8.0_77]
        at net.fortuna.ical4j.model.AbstractContentFactory.getFactory(AbstractContentFactory.java:85)
        at net.fortuna.ical4j.model.PropertyFactoryImpl.createProperty(PropertyFactoryImpl.java:78)
        at net.fortuna.ical4j.data.CalendarBuilder$ContentHandlerImpl.startProperty(CalendarBuilder.java:326)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.parse(CalendarParserImpl.java:206)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyParser.access$1100(CalendarParserImpl.java:190)
        at net.fortuna.ical4j.data.CalendarParserImpl$PropertyListParser.parse(CalendarParserImpl.java:173)
        at net.fortuna.ical4j.data.CalendarParserImpl.parse(CalendarParserImpl.java:122)
        at net.fortuna.ical4j.data.CalendarBuilder.build(CalendarBuilder.java:198)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:279)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:262)
        at org.openhab.io.caldav.internal.job.EventReloaderJob.execute(EventReloaderJob.java:112)
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)[102:org.eclipse.smarthome.core.scheduler:0.9.0.201611102032]
        ... 1 more

chris · November 19, 2016, 12:13am

I suspect that this is caused by the changes to ESH. I would suggest that you update to the latest runtime and it might start working again…

Basically ESH removed a method a few days ago, and the binding was updated to reflect this, but it means the binding is not backward compatible - it needs to be run with the latest OH runtime.

Euphi · November 19, 2016, 12:17am

Are bindingsupdated automatically, if I use the online distribution of openhab?

I was not logged in to my server at this time, but I may have installed a Binding (Astro) via PaperUI. (I’m not sure about the time and even about the day …)

chris · November 19, 2016, 12:19am

No - not as far as I know.

Euphi · November 19, 2016, 12:46am

Mmhm, so then how the binding was updated?

I configured the Zwave binding in services/addons.cfg - maybe these binding are automatically updated?

binding = zwave, mqtt, yamahareceiver,caldav-command

However - I updated OH2 to latest snapshot release and now Zwave items work fine again. (I have several new problems, but all unrelated to Zwave… )

chris · November 19, 2016, 9:45am

I don’t know, but clearly it was somehow… At least it’s working again.