CalDAV personal: Switch item ON only during server reload, but on time for OFF

[Tobi77 (Tobias)]

Dear all,

I observed a behaviour I cannot explain and it is really disturbing:
Example:
I scheduled an event to switch on a power plug at 02:00h and to switch it off at 03:00h. However, the switch is only activated at 2:22h, but deactvated on time:

The item:

Switch CalDav_Recordings_Switch "Strom für Aufnahmen" <calendar> (Entertainment) {caldavPersonal="calendar:Recordings type:'PRESENCE'"}

opehab.log:

2018-12-24 00:42:58.975 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - CalDAV IO is properly configured.
2018-12-24 00:43:13.800 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - CalDAV IO is properly configured.
2018-12-24 00:45:51.277 [INFO ] [el.core.internal.ModelRepositoryImpl] - Refreshing model 'default.items'
2018-12-24 02:00:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx.kalender.gmx.net(Tesdfghdfg@24.12.2018/02:00-24.12.2018/03:00)
2018-12-24 02:22:19.763 [INFO ] [smarthome.model.script.Regel Horizon] - Calendar event switched Horizon power plug ON: Tesdfghdfg
2018-12-24 03:00:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event END for: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx.kalender.gmx.net(Tesdfghdfg@24.12.2018/02:00-24.12.2018/03:00)
2018-12-24 03:00:00.111 [INFO ] [smarthome.model.script.Regel Horizon] - Command sent to Horizon power plug is OFF!

Note, the event is correctly found to start at 02:00h!

events.log:

2018-12-24 02:22:19.708 [ome.event.ItemCommandEvent] - Item 'CalDav_Recordings_Switch' received command ON
2018-12-24 02:22:19.718 [vent.ItemStateChangedEvent] - CalDav_Next_Recording_Begin changed from 2018-12-24T02:00:00.000+0100 to 2018-12-24T04:15:00.000+0100
2018-12-24 02:22:19.723 [vent.ItemStateChangedEvent] - CalDav_Next_Recording_Title changed from Tesdfghdfg to dfsds
2018-12-24 02:22:19.726 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_Title changed from UNDEF to Tesdfghdfg
2018-12-24 02:22:19.730 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_Begin changed from UNDEF to 2018-12-24T02:00:00.000+0100
2018-12-24 02:22:19.742 [vent.ItemStateChangedEvent] - CalDav_Next_Recording_End changed from 2018-12-24T03:00:00.000+0100 to 2018-12-24T05:00:00.000+0100
2018-12-24 02:22:19.748 [vent.ItemStateChangedEvent] - CalDav_Recordings_Switch changed from OFF to ON
2018-12-24 02:22:19.776 [ome.event.ItemCommandEvent] - Item 'Power_Plug_Horizon' received command ON
2018-12-24 02:22:19.805 [nt.ItemStatePredictedEvent] - Power_Plug_Horizon predicted to become ON
2018-12-24 02:22:19.809 [vent.ItemStateChangedEvent] - Power_Plug_Horizon changed from OFF to ON
2018-12-24 02:22:35.403 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@12564f0
...
018-12-24 03:00:00.023 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_End changed from 2018-12-24T03:00:00.000+0100 to UNDEF
2018-12-24 03:00:00.061 [ome.event.ItemCommandEvent] - Item 'CalDav_Recordings_Switch' received command OFF
2018-12-24 03:00:00.071 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_Title changed from Tesdfghdfg to UNDEF
2018-12-24 03:00:00.074 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_Begin changed from 2018-12-24T02:00:00.000+0100 to UNDEF
2018-12-24 03:00:00.084 [vent.ItemStateChangedEvent] - CalDav_Recordings_Switch changed from ON to OFF
2018-12-24 03:00:00.119 [ome.event.ItemCommandEvent] - Item 'Power_Plug_Horizon' received command OFF
2018-12-24 03:00:00.126 [nt.ItemStatePredictedEvent] - Power_Plug_Horizon predicted to become OFF
2018-12-24 03:00:00.153 [vent.ItemStateChangedEvent] - Power_Plug_Horizon changed from ON to OFF

This is the case for all other calendar events: The switch always is just activated at “xx:22h”. This corresponds to the last system boot time:

2018-12-23 18:22:08.703 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - CalDAV IO is properly configured.
2018-12-23 18:22:08.744 [INFO ] [b.core.service.AbstractActiveService] - CalDav Loader has been started
2018-12-23 18:22:08.997 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - reload job scheduled for: Recordings
2018-12-23 18:22:09.522 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-12-23 18:22:09.596 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2018-12-23 18:22:11.413 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Berlin'.
2018-12-23 18:22:11.422 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'de_DE'.
...

Is it related to my calendar settings? I used 60mins for the intervall, but I assumed this is only for the updates from the internet calendar provider, not the scheduler that triggers the event. Or do I need to set it to 1 minute to have a trigger on time?

Note, I am on milestone (which is 2.4 currently release version)

# Reload interval unit is minutes. 
# Defines how often the calendar should be reloaded from server.
# Default is 60 minutes
caldavio:Recordings:reloadInterval=60

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

Thanks for your help!

Edit: Same issue is reported here: Same issue is reported here:

Caldav peronal event sheduled only at reload

@MathiasMoog: I suffer from exactly the same problem, see CalDAV personal: Switch item ON only during server reload, but on time for OFF Were you able to solve it? Thanks a lot!

and here:

Delayed caldav execution

hi there first of all to the community and the developer, nice job dudes ! … only with the tutorials and the community-page i’ve been done a lot automation-stuff with openhab … but now no more aces left … openhab 2.1 caldav-binding running with nextcloud server everything is fine, but caldav-item-update is delayed, most of all about 6 minutes … so the execution of the event (lights on, shutter down, …) is delayed also … what can i do here ? so, if anybody have an idea, please let me know !

[namraccr]

That does seem rather odd.

You should grab a debug log during startup to see how the jobs are being scheduled.

[Tobi77 (Tobias)]

@namraccr Thanks! I will do so when I’m back home.
Did you notice the openhab.log extract that shows that the event is correctly recognized?

There is also a line in the from the system boot I posted saying
“Reload job scheduled for: Recordings”, so this seems to basically work.

But the issue is not related to the “reloadInterval=60”? That is only the server update interval, correct?

[namraccr]

That’s right, the reloadInterval should not be a factor.

[Tobi77 (Tobias)]

Here a quite complete log: There are events scheduled. Note, I had a test event for 18:10h in the calendar, but the switch is never switched on - there is no event at that time. Still, the scheduler knows the event:

2018-12-25 18:10:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: 695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)

(see details below)
However, before at 18:05 and 18:15h, the switch is set to off correctly as test events ended…

events.log:

2018-12-25 18:05:00.032 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_End changed from 2018-12-25T18:05:00.000+0100 to UNDEF
2018-12-25 18:05:00.040 [ome.event.ItemCommandEvent] - Item 'CalDav_Recordings_Switch' received command OFF
2018-12-25 18:05:00.068 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_Title changed from ertger to UNDEF
2018-12-25 18:05:00.072 [vent.ItemStateChangedEvent] - CalDav_Current_Recording_Begin changed from 2018-12-25T18:00:00.000+0100 to UNDEF
2018-12-25 18:05:00.075 [vent.ItemStateChangedEvent] - CalDav_Recordings_Switch changed from ON to OFF
2018-12-25 18:05:00.092 [ome.event.ItemCommandEvent] - Item 'Power_Plug_Horizon' received command OFF
2018-12-25 18:05:00.101 [nt.ItemStatePredictedEvent] - Power_Plug_Horizon predicted to become OFF
...
2018-12-25 18:08:55.298 [vent.ItemStateChangedEvent] - Binary_Motion_Hallway changed from ON to OFF
2018-12-25 18:11:30.874 [vent.ItemStateChangedEvent] - Heating_LivingRoom changed from 20.1 to 20.2
...
2018-12-25 18:15:00.051 [ome.event.ItemCommandEvent] - Item 'CalDav_Recordings_Switch' received command OFF

openhab.log:

2018-12-25 18:02:23.230 [INFO ] [b.core.service.AbstractActiveService] - CalDav Loader has been started
2018-12-25 18:02:23.503 [INFO ] [.io.caldav.internal.CalDavLoaderImpl] - reload job scheduled for: Recordings
2018-12-25 18:02:24.205 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-12-25 18:02:24.267 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2018-12-25 18:02:26.043 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Berlin'.
2018-12-25 18:02:26.053 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'de_DE'.
2018-12-25 18:02:38.020 [DEBUG] [caldav.internal.job.EventReloaderJob] - running EventReloaderJob for config : Recordings
2018-12-25 18:02:38.023 [DEBUG] [caldav.internal.job.EventReloaderJob] - reload cached events for config: Recordings
2018-12-25 18:02:38.170 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/6740a2c0-3d3d-406f-943e-2f3a5177c1e5_kalender_gmx_net.ics
...
2018-12-25 18:02:39.648 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Test3'
2018-12-25 18:02:39.650 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Test3'
2018-12-25 18:02:39.652 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/8aff664a-62a6-433b-960e-8662765a5286_kalender_gmx_net.ics
2018-12-25 18:02:39.801 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Tesdfghdfg'
2018-12-25 18:02:39.803 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Tesdfghdfg'
2018-12-25 18:02:39.804 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/891b87e2-9588-4d3b-951d-909833cf1961_kalender_gmx_net.ics
2018-12-25 18:02:39.977 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Test2'
2018-12-25 18:02:39.979 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Test2'
2018-12-25 18:02:39.982 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/316c740f-8a28-4fb2-b1e8-5270d413d258_kalender_gmx_net.ics
2018-12-25 18:02:40.121 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Aufnahme "Verrückt nach Meer"'
...
2018-12-25 18:02:48.067 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
2018-12-25 18:02:48.398 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:48.470 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:48.477 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@26.12.2018/16:00-26.12.2018/17:10)' @ Wed Dec 26 16:00:00 CET 2018
2018-12-25 18:02:48.481 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@26.12.2018/16:00-26.12.2018/17:10)' @ Wed Dec 26 17:10:00 CET 2018
2018-12-25 18:02:48.483 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@27.12.2018/16:00-27.12.2018/17:10)' @ Thu Dec 27 16:00:00 CET 2018
2018-12-25 18:02:48.486 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@27.12.2018/16:00-27.12.2018/17:10)' @ Thu Dec 27 17:10:00 CET 2018
2018-12-25 18:02:48.488 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/6832f1de-6112-463c-9897-aeda4b653b3d_kalender_gmx_net.ics
2018-12-25 18:02:48.605 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'werqer'
2018-12-25 18:02:48.606 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'werqer'
2018-12-25 18:02:48.608 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/f44071ab-86ce-4ef2-abef-f722c4b12786_kalender_gmx_net.ics
2018-12-25 18:02:48.884 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Aufnahme "Hot oder Schrott - Die Allestester"'
2018-12-25 18:02:48.886 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Aufnahme "Hot oder Schrott - Die Allestester"'
2018-12-25 18:02:48.888 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/7a7def8d-a29a-4d8e-8c09-a0894ac4d52b_kalender_gmx_net.ics
2018-12-25 18:02:48.954 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Test'
2018-12-25 18:02:48.955 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Test'
2018-12-25 18:02:48.957 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/2b69f8b8-fc69-40eb-a9c0-2232e54ddc5e_kalender_gmx_net.ics
2018-12-25 18:02:49.023 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event ''
2018-12-25 18:02:49.024 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event ''
2018-12-25 18:02:49.026 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/6cde1c67-d9ee-45de-8936-4d5a6a99801c_kalender_gmx_net.ics
2018-12-25 18:02:49.090 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Hallo'
2018-12-25 18:02:49.091 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Hallo'
2018-12-25 18:02:49.093 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/807a00c8-74cb-498c-b8ed-68065e981815_kalender_gmx_net.ics
2018-12-25 18:02:49.159 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Aufnahme "Goodbye Deutschland"'
2018-12-25 18:02:49.161 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Aufnahme "Goodbye Deutschland"'
2018-12-25 18:02:49.163 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/6255eae4-83c9-4265-8dfd-235e4390c7df_kalender_gmx_net.ics
2018-12-25 18:02:49.229 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'rqwerqw'
2018-12-25 18:02:49.230 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'rqwerqw'
2018-12-25 18:02:49.232 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/132c2c84-cd08-4c2b-847d-add37f8c8329_kalender_gmx_net.ics
2018-12-25 18:02:49.291 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Test 2'
2018-12-25 18:02:49.292 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Test 2'
2018-12-25 18:02:49.294 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/a9b7fdf9-67c6-4280-9ba1-ebf507e8db3b_kalender_gmx_net.ics
2018-12-25 18:02:49.355 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'dfsds'
2018-12-25 18:02:49.356 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'dfsds'
2018-12-25 18:02:49.358 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/a25d7ac2-6052-4b4a-b14e-c3eee59564e5_kalender_gmx_net.ics
2018-12-25 18:02:49.382 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'jdbc.persist'
2018-12-25 18:02:49.420 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Test3'
2018-12-25 18:02:49.422 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Test3'
2018-12-25 18:02:49.424 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events from file : etc/caldav/Recordings/9d354972-2964-4b0c-9d9b-c3e76f428696_kalender_gmx_net.ics
2018-12-25 18:02:49.485 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'Test'
2018-12-25 18:02:49.487 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'Test'
2018-12-25 18:02:49.489 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events for config: Recordings
2018-12-25 18:02:49.523 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 6740a2c0-3d3d-406f-943e-2f3a5177c1e5.kalender.gmx.net -- filename : 6740a2c0-3d3d-406f-943e-2f3a5177c1e5_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:39.638+01:00 -- lastchanged : 2018-12-24T01:22:26.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.525 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 8aff664a-62a6-433b-960e-8662765a5286.kalender.gmx.net -- filename : 8aff664a-62a6-433b-960e-8662765a5286_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:39.800+01:00 -- lastchanged : 2018-12-24T03:22:19.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.527 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 6832f1de-6112-463c-9897-aeda4b653b3d.kalender.gmx.net -- filename : 6832f1de-6112-463c-9897-aeda4b653b3d_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:48.603+01:00 -- lastchanged : 2018-12-24T07:22:19.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.529 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 2b69f8b8-fc69-40eb-a9c0-2232e54ddc5e.kalender.gmx.net -- filename : 2b69f8b8-fc69-40eb-a9c0-2232e54ddc5e_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.022+01:00 -- lastchanged : 2018-12-24T05:22:19.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.531 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : a9b7fdf9-67c6-4280-9ba1-ebf507e8db3b.kalender.gmx.net -- filename : a9b7fdf9-67c6-4280-9ba1-ebf507e8db3b_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.354+01:00 -- lastchanged : 2018-12-24T05:22:19.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.533 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 7a7def8d-a29a-4d8e-8c09-a0894ac4d52b.kalender.gmx.net -- filename : 7a7def8d-a29a-4d8e-8c09-a0894ac4d52b_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:48.953+01:00 -- lastchanged : 2018-12-24T01:22:25.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.535 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 807a00c8-74cb-498c-b8ed-68065e981815.kalender.gmx.net -- filename : 807a00c8-74cb-498c-b8ed-68065e981815_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.158+01:00 -- lastchanged : 2018-12-24T01:22:27.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.537 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 6255eae4-83c9-4265-8dfd-235e4390c7df.kalender.gmx.net -- filename : 6255eae4-83c9-4265-8dfd-235e4390c7df_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.228+01:00 -- lastchanged : 2018-12-24T08:22:19.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.539 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 891b87e2-9588-4d3b-951d-909833cf1961.kalender.gmx.net -- filename : 891b87e2-9588-4d3b-951d-909833cf1961_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:39.976+01:00 -- lastchanged : 2018-12-24T01:22:26.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.542 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 9d354972-2964-4b0c-9d9b-c3e76f428696.kalender.gmx.net -- filename : 9d354972-2964-4b0c-9d9b-c3e76f428696_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.485+01:00 -- lastchanged : 2018-12-24T01:22:27.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.543 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : f44071ab-86ce-4ef2-abef-f722c4b12786.kalender.gmx.net -- filename : f44071ab-86ce-4ef2-abef-f722c4b12786_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:48.882+01:00 -- lastchanged : 2018-12-24T01:22:26.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.545 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 6cde1c67-d9ee-45de-8936-4d5a6a99801c.kalender.gmx.net -- filename : 6cde1c67-d9ee-45de-8936-4d5a6a99801c_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.089+01:00 -- lastchanged : 2018-12-24T01:22:27.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.547 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 132c2c84-cd08-4c2b-847d-add37f8c8329.kalender.gmx.net -- filename : 132c2c84-cd08-4c2b-847d-add37f8c8329_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.290+01:00 -- lastchanged : 2018-12-24T01:22:26.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.549 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net -- filename : 316c740f-8a28-4fb2-b1e8-5270d413d258_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:40.078+01:00 -- lastchanged : 2018-12-24T01:22:25.000+01:00 -- ishistoric : false
2018-12-25 18:02:49.551 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventlist contains the event : Aufnahme "Verrückt nach Meer" -- deb : 2018-12-26T16:00:00.000+01:00 -- fin : 2018-12-26T17:10:00.000+01:00 -- lastchanged 2018-12-24T01:22:25.000+01:00
2018-12-25 18:02:49.554 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventlist contains the event : Aufnahme "Verrückt nach Meer" -- deb : 2018-12-27T16:00:00.000+01:00 -- fin : 2018-12-27T17:10:00.000+01:00 -- lastchanged 2018-12-24T01:22:25.000+01:00
2018-12-25 18:02:49.555 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : a25d7ac2-6052-4b4a-b14e-c3eee59564e5.kalender.gmx.net -- filename : a25d7ac2-6052-4b4a-b14e-c3eee59564e5_kalender_gmx_net -- calcuntil : 2018-12-27T18:02:49.419+01:00 -- lastchanged : 2018-12-24T01:22:27.000+01:00 -- ishistoric : false
2018-12-25 18:02:51.114 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /begenda/dav/d93e07c2-ee83-4abc-a12f-3c7d41ec7c3d/calendar/08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net.ics (FSchangedTS not valid)
2018-12-25 18:02:51.371 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event '52342'
2018-12-25 18:02:51.374 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:51.378 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net(52342@25.12.2018/18:30-25.12.2018/18:35)' @ Tue Dec 25 18:30:00 CET 2018
2018-12-25 18:02:51.381 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net(52342@25.12.2018/18:30-25.12.2018/18:35)' @ Tue Dec 25 18:35:00 CET 2018
2018-12-25 18:02:51.439 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /begenda/dav/d93e07c2-ee83-4abc-a12f-3c7d41ec7c3d/calendar/832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net.ics (FSchangedTS not valid)
2018-12-25 18:02:51.618 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'rqw3er332'
2018-12-25 18:02:51.622 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:51.626 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net(rqw3er332@25.12.2018/18:20-25.12.2018/18:25)' @ Tue Dec 25 18:20:00 CET 2018
2018-12-25 18:02:51.630 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net(rqw3er332@25.12.2018/18:20-25.12.2018/18:25)' @ Tue Dec 25 18:25:00 CET 2018
2018-12-25 18:02:51.651 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /begenda/dav/d93e07c2-ee83-4abc-a12f-3c7d41ec7c3d/calendar/40738883-f803-4b98-9230-fc55206597ef.kalender.gmx.net.ics (FSchangedTS not valid)
2018-12-25 18:02:51.833 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'w3323r'
2018-12-25 18:02:51.837 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:51.841 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '40738883-f803-4b98-9230-fc55206597ef.kalender.gmx.net(w3323r@25.12.2018/19:00-25.12.2018/19:30)' @ Tue Dec 25 19:00:00 CET 2018
2018-12-25 18:02:51.844 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '40738883-f803-4b98-9230-fc55206597ef.kalender.gmx.net(w3323r@25.12.2018/19:00-25.12.2018/19:30)' @ Tue Dec 25 19:30:00 CET 2018
2018-12-25 18:02:51.860 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /begenda/dav/d93e07c2-ee83-4abc-a12f-3c7d41ec7c3d/calendar/695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net.ics (FSchangedTS not valid)
2018-12-25 18:02:52.054 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'sfsdfsa'
2018-12-25 18:02:52.057 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:52.061 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)' @ Tue Dec 25 18:10:00 CET 2018
2018-12-25 18:02:52.065 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)' @ Tue Dec 25 18:15:00 CET 2018
2018-12-25 18:02:52.088 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /begenda/dav/d93e07c2-ee83-4abc-a12f-3c7d41ec7c3d/calendar/0cdeb795-7dfd-46ce-9ecb-3ac2ea908548.kalender.gmx.net.ics (FSchangedTS not valid)
2018-12-25 18:02:52.263 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event '3333r4f'
2018-12-25 18:02:52.267 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:52.271 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '0cdeb795-7dfd-46ce-9ecb-3ac2ea908548.kalender.gmx.net(3333r4f@25.12.2018/20:00-25.12.2018/21:00)' @ Tue Dec 25 20:00:00 CET 2018
2018-12-25 18:02:52.274 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '0cdeb795-7dfd-46ce-9ecb-3ac2ea908548.kalender.gmx.net(3333r4f@25.12.2018/20:00-25.12.2018/21:00)' @ Tue Dec 25 21:00:00 CET 2018
2018-12-25 18:02:52.290 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /begenda/dav/d93e07c2-ee83-4abc-a12f-3c7d41ec7c3d/calendar/804b2b7c-16b6-48e7-81c2-4179f22072f4.kalender.gmx.net.ics (FSchangedTS not valid)
2018-12-25 18:02:52.445 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-12-25 18:02:52.483 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'ertger'
2018-12-25 18:02:52.486 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing periods...
2018-12-25 18:02:52.489 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '804b2b7c-16b6-48e7-81c2-4179f22072f4.kalender.gmx.net(ertger@25.12.2018/18:00-25.12.2018/18:05)' @ Tue Dec 25 18:00:00 CET 2018
2018-12-25 18:02:52.492 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '804b2b7c-16b6-48e7-81c2-4179f22072f4.kalender.gmx.net(ertger@25.12.2018/18:00-25.12.2018/18:05)' @ Tue Dec 25 18:05:00 CET 2018
2018-12-25 18:02:52.507 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: 804b2b7c-16b6-48e7-81c2-4179f22072f4.kalender.gmx.net(ertger@25.12.2018/18:00-25.12.2018/18:05)
2018-12-25 18:02:52.513 [DEBUG] [caldav.internal.job.EventReloaderJob] - jobs scheduled : 
2018-12-25 18:02:52.515 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-804b2b7c-16b6-48e7-81c2-4179f22072f4.kalender.gmx.net(ertger@25.12.2018/18:00-25.12.2018/18:05)-0 - [groupName] : event-end - Tue Dec 25 18:05:00 CET 2018
2018-12-25 18:02:52.523 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net(52342@25.12.2018/18:30-25.12.2018/18:35)-0 - [groupName] : event-end - Tue Dec 25 18:35:00 CET 2018
2018-12-25 18:02:52.525 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@26.12.2018/16:00-26.12.2018/17:10)-0 - [groupName] : event-end - Wed Dec 26 17:10:00 CET 2018
2018-12-25 18:02:52.528 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-0cdeb795-7dfd-46ce-9ecb-3ac2ea908548.kalender.gmx.net(3333r4f@25.12.2018/20:00-25.12.2018/21:00)-0 - [groupName] : event-end - Tue Dec 25 21:00:00 CET 2018
2018-12-25 18:02:52.535 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-40738883-f803-4b98-9230-fc55206597ef.kalender.gmx.net(w3323r@25.12.2018/19:00-25.12.2018/19:30)-0 - [groupName] : event-end - Tue Dec 25 19:30:00 CET 2018
2018-12-25 18:02:52.538 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@27.12.2018/16:00-27.12.2018/17:10)-1 - [groupName] : event-end - Thu Dec 27 17:10:00 CET 2018
2018-12-25 18:02:52.540 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)-0 - [groupName] : event-end - Tue Dec 25 18:15:00 CET 2018
2018-12-25 18:02:52.541 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net(rqw3er332@25.12.2018/18:20-25.12.2018/18:25)-0 - [groupName] : event-end - Tue Dec 25 18:25:00 CET 2018
2018-12-25 18:02:52.547 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : Recordings - [groupName] : event-reloader - Tue Dec 25 19:02:33 CET 2018
2018-12-25 18:02:52.550 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-0cdeb795-7dfd-46ce-9ecb-3ac2ea908548.kalender.gmx.net(3333r4f@25.12.2018/20:00-25.12.2018/21:00)-0 - [groupName] : event-start - Tue Dec 25 20:00:00 CET 2018
2018-12-25 18:02:52.552 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@26.12.2018/16:00-26.12.2018/17:10)-0 - [groupName] : event-start - Wed Dec 26 16:00:00 CET 2018
2018-12-25 18:02:52.554 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)-0 - [groupName] : event-start - Tue Dec 25 18:10:00 CET 2018
2018-12-25 18:02:52.556 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-316c740f-8a28-4fb2-b1e8-5270d413d258.kalender.gmx.net(Aufnahme "Verrückt nach Meer"@27.12.2018/16:00-27.12.2018/17:10)-1 - [groupName] : event-start - Thu Dec 27 16:00:00 CET 2018
2018-12-25 18:02:52.559 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-40738883-f803-4b98-9230-fc55206597ef.kalender.gmx.net(w3323r@25.12.2018/19:00-25.12.2018/19:30)-0 - [groupName] : event-start - Tue Dec 25 19:00:00 CET 2018
2018-12-25 18:02:52.560 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net(52342@25.12.2018/18:30-25.12.2018/18:35)-0 - [groupName] : event-start - Tue Dec 25 18:30:00 CET 2018
2018-12-25 18:02:52.563 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net(rqw3er332@25.12.2018/18:20-25.12.2018/18:25)-0 - [groupName] : event-start - Tue Dec 25 18:20:00 CET 2018
...
2018-12-25 18:03:07.512 [DEBUG] [org.openhab.binding.caldav-personal ] - BundleEvent STARTING - org.openhab.binding.caldav-personal
2018-12-25 18:03:07.570 [DEBUG] [org.openhab.binding.caldav-personal ] - ServiceEvent REGISTERED - {org.openhab.model.item.binding.BindingConfigReader, org.openhab.binding.caldav_personal.CalDavBindingProvider}={service.id=431, service.bundleid=232, service.scope=bundle, component.name=org.openhab.binding.caldav_personal.caldavbindingprovider, component.id=269} - org.openhab.binding.caldav-personal
2018-12-25 18:03:07.573 [DEBUG] [org.openhab.binding.caldav-personal ] - BundleEvent STARTED - org.openhab.binding.caldav-personal
2018-12-25 18:03:08.024 [DEBUG] [org.openhab.binding.caldav-personal ] - ServiceEvent REGISTERED - {org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService}={service.id=440, service.bundleid=232, service.scope=bundle, event.topics=openhab/command/*, service.pid=org.openhab.caldavPersonal, component.name=org.openhab.binding.caldav_personal, component.id=268} - org.openhab.binding.caldav-personal
2018-12-25 18:03:08.032 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[Recordings], from=2018-12-25T18:03:08.027+01:00, to=null, sort=ASCENDING, filterName=null] with 8 entries
...
2018-12-25 18:05:00.014 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event END for: 804b2b7c-16b6-48e7-81c2-4179f22072f4.kalender.gmx.net(ertger@25.12.2018/18:00-25.12.2018/18:05)
2018-12-25 18:05:00.018 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[Recordings], from=2018-12-25T18:05:00.017+01:00, to=null, sort=ASCENDING, filterName=null] with 7 entries
2018-12-25 18:05:00.086 [INFO ] [smarthome.model.script.Regel Horizon] - Command sent to Horizon power plug is OFF!
2018-12-25 18:10:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: 695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)
2018-12-25 18:15:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event END for: 695dbae6-1261-4370-b295-fbe2562359e0.kalender.gmx.net(sfsdfsa@25.12.2018/18:10-25.12.2018/18:15)
2018-12-25 18:15:00.012 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[Recordings], from=2018-12-25T18:15:00.011+01:00, to=null, sort=ASCENDING, filterName=null] with 6 entries
2018-12-25 18:20:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: 832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net(rqw3er332@25.12.2018/18:20-25.12.2018/18:25)
2018-12-25 18:25:00.015 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event END for: 832c4bd3-9ce6-47f3-b8be-e1de737d2c57.kalender.gmx.net(rqw3er332@25.12.2018/18:20-25.12.2018/18:25)
2018-12-25 18:25:00.020 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[Recordings], from=2018-12-25T18:25:00.019+01:00, to=null, sort=ASCENDING, filterName=null] with 5 entries
2018-12-25 18:30:00.015 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: 08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net(52342@25.12.2018/18:30-25.12.2018/18:35)
2018-12-25 18:35:00.005 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event END for: 08bfbaa6-5545-4229-b42f-f1642f895492.kalender.gmx.net(52342@25.12.2018/18:30-25.12.2018/18:35)
2018-12-25 18:35:00.025 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[Recordings], from=2018-12-25T18:35:00.011+01:00, to=null, sort=ASCENDING, filterName=null] with 4 entries

Please help - thanks a lot!

[Tobi77 (Tobias)]

No idea? Hmm. Should I maybe reinstall the system?

[Tobi77 (Tobias)]

Ich reinstalled OH and caldav-personal separately just to see if that helps. Unfortunately not

[namraccr]

2018-12-24 02:00:00.003 [INFO ] [nhab.io.caldav.internal.job.EventJob] - event BEGIN for: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx.kalender.gmx.net(Tesdfghdfg@24.12.2018/02:00-24.12.2018/03:00)
2018-12-24 02:22:19.763 [INFO ] [smarthome.model.script.Regel Horizon] - Calendar event switched Horizon power plug ON: Tesdfghdfg

What are the contents of this script?

[Tobi77 (Tobias)]

@namraccr This is just a rule that ensures our TV recorder (Horizon box) is not switched off manually while a recording is running. So both a manual switch (“Horizon”) or the calendar may switch the box on, but it is only switched off if both the calendar and the manual switch are off…

rule "Regel Horizon"
when
  Item CalDav_Recordings_Switch changed or Item Horizon changed
then
  if (CalDav_Recordings_Switch.state == ON)
  {
    logInfo("Regel Horizon", "Calendar event switched Horizon power plug ON: " + CalDav_Current_Recording_Title.state.toString)    
    Power_Plug_Horizon.sendCommand(ON)
  }
  else if(Horizon.state == ON)
  {
    logInfo("Regel Horizon", "Horizon command event switched Horizon power plug ON!")        
    Power_Plug_Horizon.sendCommand(ON)      
  }
  else
  {
    logInfo("Regel Horizon", "Command sent to Horizon power plug is OFF!")    
    Power_Plug_Horizon.sendCommand(OFF)    
  }
end

But it is the switch CalDav_Recordings_Switch that triggeres everything, at it receives no fire for ON at the time of the calendar event begin but only during the 60min periodic server check (xx:22h) …

Thanks again

[Tobi77 (Tobias)]

I also deleted the rule, cleared the cache and rebooted to see whether it makes a difference. As expected, the behavior of CalDav_Recordings_Switch itself did not change and I still miss the fire for ON on time.

What drives me crazy is the fact that the scheduler seems to recognize the events (see the job list) and both start and end are associated with jobs, but only thre end receives the return event list for CalDavQuery trigger on time that seems to fire my switch.

Can I manually check the scheduler that should fire the switch?

[Tobi77 (Tobias)]

I am lost as I am no developer. Any hint someone?

[Tobi77 (Tobias)]

Maybe someone could do a test and try to use a Switch item with CalDAV-personal, in the ideal case using a RPi? I found no example on the web of people really using the binding with the Switch item. Most people use CalDAV-command for firing events. So maybe the issue has simply not been discovered yet? Thanks!

[Tobi77 (Tobias)]

I did a completely new and fresh install of the whole system. Log files attached, TRACE level is set. There is an event that should switch ON at 15:05h and OFF at 15:10h. The only installed add-on is CalDAV-personal!

Note, after installation, an error occured on specifying caldavio.cfg and caldavPersonal.cfg. Maybe, because I deleted some older entries in the calendar and thus confused the database? Therefore, I rebooted the system.

events.log (1.4 KB)
openhab.log (58.9 KB)

[H102 (CM6.5 H102)]

What version of caldav is this?

[Tobi77 (Tobias)]

@H102 Installed version is the one I got during the installation of OH 2.4 release, 1.13.0

[H102 (CM6.5 H102)]

You can try the lastest 1.14.0 found here:

Personal
https://openhab.jfrog.io/openhab/libs-pullrequest-local/org/openhab/binding/org.openhab.binding.caldav-personal/

And Command

https://openhab.jfrog.io/openhab/libs-pullrequest-local/org/openhab/binding/org.openhab.binding.caldav-command/

[Tobi77 (Tobias)]

Thanks a lot!

Is there a changelog? As I am a bit skeptic about manual installations: Would I get this with snaptshot releases?

[H102 (CM6.5 H102)]

@Tobi77

Is there a changelog? As I am a bit skeptic about manual installations: Would I get this with snaptshot releases?

If there is you will need to search and find it b/c I have no clue where it would be.

Uninstall the current binding via PaperUI and place the jar file in /usr/share/openhab2/addons then restart OH. If you don’t like the results you can always remove the addon and go back.

BTW the 1.14.0 version has helped others. Don’t remember their exact issue but it can’t hurt to give it a try.

Best of Luck.

@Tobi77 EDIT: Here are the step I recommend taking.

1. Uninstall caldav bindings through PapierUI
2. Stop openHAB service > sudo service openhab2 stop
3. Clean the cache > sudo openhab-cli clean-cache
4. Place the 1.14.0 bindings in the addons folder
5. Start openHAB again > sudo service openhab2 start

[Tobi77 (Tobias)]

Thanks. Which folder should I use for this 1.x binding? I use a standard openHABian installation and the SAMBA shares…

[H102 (CM6.5 H102)]

@Tobi77

Which folder should I use for this 1.x binding?

Not sure what you mean. Folder for what?