OH2.2 Caldav error

Setup, Configuration and Use Installation
,
1

Windows 10 64 bit
OH2.2
Caldav binding v1.12.0 Snapshot from 2017-12-31

Dear all

from time to time I see the following error in my log:

11-Jan-2018 17:00:00.000 [INFO ] [org.openhab.io.caldav.internal.job.EventJob       ] - event BEGIN for: 8k9o4lc5a1d1mvh2ailk5e6m2g@google.com(Garden Power Time@11.01.2018/17:00-11.01.2018/22:00)
11-Jan-2018 17:00:00.002 [INFO ] [nhab.binding.caldav_command.internal.CalDavBinding] - sending command '1' to item 'GardenPowerTime' from event '8k9o4lc5a1d1mvh2ailk5e6m2g@google.com(Garden Power Time@11.01.2018/17:00-11.01.2018/22:00)'
11-Jan-2018 17:00:00.002 [INFO ] [nhab.binding.caldav_command.internal.CalDavBinding] - sending command 'ON' to item 'SD_EG_Terrasse_15b' from event '8k9o4lc5a1d1mvh2ailk5e6m2g@google.com(Garden Power Time@11.01.2018/17:00-11.01.2018/22:00)'
11-Jan-2018 17:00:00.003 [INFO ] [org.openhab.io.caldav.internal.job.EventJob       ] - event BEGIN for: 8k9o4lc5a1d1mvh2ailk5e6m2g@google.com(Garden Power Time@12.01.2018/17:00-12.01.2018/22:00)
11-Jan-2018 17:00:00.004 [INFO ] [nhab.binding.caldav_command.internal.CalDavBinding] - sending command '1' to item 'GardenPowerTime' from event '8k9o4lc5a1d1mvh2ailk5e6m2g@google.com(Garden Power Time@12.01.2018/17:00-12.01.2018/22:00)'
11-Jan-2018 17:00:00.005 [INFO ] [nhab.binding.caldav_command.internal.CalDavBinding] - sending command 'ON' to item 'SD_EG_Terrasse_15b' from event '8k9o4lc5a1d1mvh2ailk5e6m2g@google.com(Garden Power Time@12.01.2018/17:00-12.01.2018/22:00)'
11-Jan-2018 17:00:00.006 [ERROR] [org.openhab.io.caldav.internal.job.EventJob       ] - error executing event job
org.quartz.JobExecutionException: cannot get recurence-event for config: smarthome and eventId: 8k9o4lc5a1d1mvh2ailk5e6m2g@google.com and occurrence: 2
	at org.openhab.io.caldav.internal.job.EventJob.execute(EventJob.java:50) [253:org.openhab.io.caldav:1.12.0.201712300210]
	at org.quartz.core.JobRunShell.run(JobRunShell.java:202) [115:org.eclipse.smarthome.core.scheduler:0.10.0.b1]
	at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573) [115:org.eclipse.smarthome.core.scheduler:0.10.0.b1]

I did not oberserve any functional impacts, the according event 8k9o4lc5a1d1mvh2ailk5e6m2g is executed properly. But what exactly does this error mean and how can I get rid of it?

Thanks & regards
John

2

Not really sure I know what that error means, but is your setup in compliance with this instruction in the docs?

You have to set the preloadInterval to a higher or equal value as the recurring events in the calendar exists.
3

I configured it like this (which is in line with the documentation):

# This defines which events are relevant for execution. Unit is in minutes.
# Default is 1 Day (1440 minutes)
caldavio:smarthome:preloadTime=2880

My recurring events are on daily base.

For testing purposes I increased the preloadTime from 2880 to 28800 (2 day to 20 days).

4

Additional observations:

  • the error happens always at the end of an event (but the END condition is executed correctly)
  • the time of the event doesn’t matter
  • the executed commands doesn’t matter
5

Weird. I don’t see any END conditions in that log snippet. I also don’t get why there are 3 executions in less than a second for a single event.

Perhaps details of the events would shed light. A trace log could also be helpful.

6

Here I need input - how can I provide this?

7

You can set the bindings to TRACE in the karaf console like this:

log:set TRACE org.openhab.io.caldav
log:set TRACE org.openhab.binding.caldav-command

Your logs will then show additional [TRACE] and [DEBUG] messages.