[caldav] Binding always uses old entries from Google calender

Bredmich · January 3, 2019, 3:31pm

Hi there,

i´m working with caldav and a Google calender to get an information about upcoming waste collection.
Currently the caldav binding loads the same event from last year instead of a new event that´s due on 05.01.2019.

caldavio.cfg

caldavio:Abfall:url=https://www.google.com/calendar/dav/<MyCalenderID>@group.calendar.google.com/events
caldavio:Abfall:username=<MyUsername> //without @gmail.com
caldavio:Abfall:password=<MyPassword>
caldavio:Abfall:reloadInterval=60
caldavio:Abfall:preloadTime=14400
caldavio:Abfall:disableCertificateVerification=true

I already checked the caldavio.config in userdate\config\org\openhab and it has the correct parameters from my caldavio.cfg

caldav.items

String calAbfallTonne "Tonne [MAP(muell.map):%s]" <tonne> (gPersist) {caldavPersonal="calendar:Abfall type:EVENT eventNr:1 value:NAME"}
String calAbfallMieter "Tonne [%s]" <tonne> (gPersist) {caldavPersonal="calendar:Abfall type:EVENT eventNr:1 value:DESCRIPTION"}
DateTime calAbfallDate "Nächste Leerung [%1$td.%1$tm.%1$tY]" <calendar> (gPersist) {caldavPersonal="calendar:Abfall type:EVENT eventNr:1 value:START"}

In userdata\etc\caldav is the folder with the correct name Abfall and all of the entries.
Even the entry for the 05.01.2019

DTSTART;VALUE=DATE:20190105
DTEND;VALUE=DATE:20190106
SUMMARY:grau

But the binding always uses this entry:

DTSTART;VALUE=DATE:20181227
DTEND;VALUE=DATE:20181228
SUMMARY:gelb

I changed the bindings logging to trace and had a look at the logs.

2019-01-03 16:17:32.223 [DEBUG] [caldav.internal.job.EventReloaderJob] - running EventReloaderJob for config : Abfall
2019-01-03 16:17:32.227 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading events for config: Abfall
2019-01-03 16:17:32.230 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 729cbu2hq4e55mpl8idrd1bpti@google.com -- filename : 729cbu2hq4e55mpl8idrd1bpti_google_com -- calcuntil : 2019-01-13T15:17:33.613+01:00 -- lastchanged : 2019-01-03T15:17:33.427+01:00 -- ishistoric : false
...
2019-01-03 16:17:32.287 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 00h7nbqf19d8vsm9acsri3k025@google.com -- filename : 00h7nbqf19d8vsm9acsri3k025_google_com -- calcuntil : 2019-01-13T15:17:36.596+01:00 -- lastchanged : 2019-01-03T15:17:36.425+01:00 -- ishistoric : true
2019-01-03 16:17:32.291 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventlist contains the event : grau -- deb : 2019-01-05T00:00:00.000+01:00 -- fin : 2019-01-06T00:00:00.000+01:00 -- lastchanged 2018-12-20T12:32:57.000+01:00
2019-01-03 16:17:32.295 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 46ufn1aev77jrtjlvca6if3lrr@google.com -- filename : 46ufn1aev77jrtjlvca6if3lrr_google_com -- calcuntil : 2019-01-13T15:17:36.826+01:00 -- lastchanged : 2019-01-03T15:17:36.634+01:00 -- ishistoric : true
2019-01-03 16:17:32.298 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventlist contains the event : gelb -- deb : 2019-01-09T00:00:00.000+01:00 -- fin : 2019-01-10T00:00:00.000+01:00 -- lastchanged 2018-12-20T12:33:24.000+01:00
2019-01-03 16:17:32.301 [DEBUG] [caldav.internal.job.EventReloaderJob] - old eventcontainer -- id : 2m1vnrcnqsleac34v6lghiq67v@google.com -- filename : 2m1vnrcnqsleac34v6lghiq67v_google_com -- calcuntil : 2019-01-13T15:17:37.043+01:00 -- lastchanged : 2019-01-03T15:17:36.881+01:00 -- ishistoric : false
...
2019-01-03 16:17:32.510 [TRACE] [org.openhab.io.caldav.internal.Util ] - connecting to caldav 'Abfall' with disabled certificate verification (url=https://www.google.com/calendar/dav/<MyCalenderID>@group.calendar.google.com/events, username=<MyUsername>, password=<MyPassword>
2019-01-03 16:17:33.265 [TRACE] [caldav.internal.job.EventReloaderJob] - before load events : oldeventids contains : [729cbu2hq4e55mpl8idrd1bpti_google_com, ...]
2019-01-03 16:17:33.268 [TRACE] [caldav.internal.job.EventReloaderJob] - eventContainer found: true
2019-01-03 16:17:33.271 [TRACE] [caldav.internal.job.EventReloaderJob] - last resource modification: 2019-01-03T16:17:33.268+01:00
2019-01-03 16:17:33.272 [TRACE] [caldav.internal.job.EventReloaderJob] - last change of already loaded event: 2019-01-03T15:17:33.199+01:00
2019-01-03 16:17:33.274 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /calendar/dav/<MyCalenderID>@group.calendar.google.com/events/634p6454s2tda8l2sa6615sfem@google.com.ics (FSchangedTS not valid)
2019-01-03 16:17:33.276 [TRACE] [caldav.internal.job.EventReloaderJob] - Raw URL: https://www.google.com/calendar/dav/<MyCalenderID>@group.calendar.google.com/events
2019-01-03 16:17:33.279 [TRACE] [caldav.internal.job.EventReloaderJob] - URL after encoding: https://www.google.com/calendar/dav/<MyCalenderID>@group.calendar.google.com/events/634p6454s2tda8l2sa6615sfem@google.com.ics
2019-01-03 16:17:33.576 [TRACE] [caldav.internal.job.EventReloaderJob] - loadFrom = 2019-01-03T16:17:33.575+01:00
2019-01-03 16:17:33.578 [TRACE] [caldav.internal.job.EventReloaderJob] - loadTo = 2019-01-13T16:17:33.578+01:00
2019-01-03 16:17:33.580 [TRACE] [caldav.internal.job.EventReloaderJob] - loading event: 634p6454s2tda8l2sa6615sfem@google.com:grau
2019-01-03 16:17:33.583 [TRACE] [caldav.internal.job.EventReloaderJob] - overriding lastmodified from file FS (2019-01-03T16:17:33.268+01:00) with event's last-modified property (2018-03-31T10:39:52.000+02:00)
2019-01-03 16:17:33.585 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'grau'
2019-01-03 16:17:33.587 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'grau'
2019-01-03 16:17:33.588 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - event is already in event map and newer -> delete the old one, reschedule timer
...
2019-01-03 16:17:37.369 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_command.internal.CalDavBinding@1232d7b
2019-01-03 16:17:37.371 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_personal.internal.CalDavBinding@1930e0c
2019-01-03 16:17:37.372 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_command.internal.CalDavBinding@1232d7b
2019-01-03 16:17:37.374 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_personal.internal.CalDavBinding@1930e0c
2019-01-03 16:17:37.377 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - old start job (event-start-00h7nbqf19d8vsm9acsri3k025@google.com(grau@05.01.2019/00:00-06.01.2019/00:00)-0) deleted? false/true
2019-01-03 16:17:37.379 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '00h7nbqf19d8vsm9acsri3k025@google.com(grau@05.01.2019/00:00-06.01.2019/00:00)' @ Sat Jan 05 00:00:00 CET 2019
2019-01-03 16:17:37.382 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - old end job (event-end-00h7nbqf19d8vsm9acsri3k025@google.com(grau@05.01.2019/00:00-06.01.2019/00:00)-0) deleted? false/true
2019-01-03 16:17:37.385 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '00h7nbqf19d8vsm9acsri3k025@google.com(grau@05.01.2019/00:00-06.01.2019/00:00)' @ Sun Jan 06 00:00:00 CET 2019
...
2019-01-03 16:17:37.635 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_command.internal.CalDavBinding@1232d7b
2019-01-03 16:17:37.636 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_personal.internal.CalDavBinding@1930e0c
2019-01-03 16:17:37.638 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_command.internal.CalDavBinding@1232d7b
2019-01-03 16:17:37.639 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - notify listener... org.openhab.binding.caldav_personal.internal.CalDavBinding@1930e0c
2019-01-03 16:17:37.642 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - old start job (event-start-46ufn1aev77jrtjlvca6if3lrr@google.com(gelb@09.01.2019/00:00-10.01.2019/00:00)-0) deleted? false/true
2019-01-03 16:17:37.644 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - begin timer scheduled for event '46ufn1aev77jrtjlvca6if3lrr@google.com(gelb@09.01.2019/00:00-10.01.2019/00:00)' @ Wed Jan 09 00:00:00 CET 2019
2019-01-03 16:17:37.647 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - old end job (event-end-46ufn1aev77jrtjlvca6if3lrr@google.com(gelb@09.01.2019/00:00-10.01.2019/00:00)-0) deleted? false/true
2019-01-03 16:17:37.649 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - end timer scheduled for event '46ufn1aev77jrtjlvca6if3lrr@google.com(gelb@09.01.2019/00:00-10.01.2019/00:00)' @ Thu Jan 10 00:00:00 CET 2019
...
2019-01-03 16:18:01.975 [TRACE] [caldav.internal.job.EventReloaderJob] - eventContainer found: true
2019-01-03 16:18:01.978 [TRACE] [caldav.internal.job.EventReloaderJob] - last resource modification: 2019-01-03T16:18:01.975+01:00
2019-01-03 16:18:01.980 [TRACE] [caldav.internal.job.EventReloaderJob] - last change of already loaded event: 2019-01-03T15:17:58.780+01:00
2019-01-03 16:18:01.982 [DEBUG] [caldav.internal.job.EventReloaderJob] - loading resource: /calendar/dav/<MyCalenderID>@group.calendar.google.com/events/3dog9hi39flfkre3f3h3m6bn69@google.com.ics (FSchangedTS not valid)
2019-01-03 16:18:01.985 [TRACE] [caldav.internal.job.EventReloaderJob] - Raw URL: https://www.google.com/calendar/dav/<MyCalenderID>@group.calendar.google.com/events
2019-01-03 16:18:01.988 [TRACE] [caldav.internal.job.EventReloaderJob] - URL after encoding: https://www.google.com/calendar/dav/<MyCalenderID>@group.calendar.google.com/events/3dog9hi39flfkre3f3h3m6bn69@google.com.ics
2019-01-03 16:18:02.194 [TRACE] [caldav.internal.job.EventReloaderJob] - loadFrom = 2019-01-03T16:18:02.194+01:00
2019-01-03 16:18:02.195 [TRACE] [caldav.internal.job.EventReloaderJob] - loadTo = 2019-01-13T16:18:02.195+01:00
2019-01-03 16:18:02.196 [TRACE] [caldav.internal.job.EventReloaderJob] - loading event: 3dog9hi39flfkre3f3h3m6bn69@google.com:grau
2019-01-03 16:18:02.198 [TRACE] [caldav.internal.job.EventReloaderJob] - overriding lastmodified from file FS (2019-01-03T16:18:01.975+01:00) with event's last-modified property (2018-03-31T10:42:14.000+02:00)
2019-01-03 16:18:02.200 [DEBUG] [caldav.internal.job.EventReloaderJob] - Processing event 'grau'
2019-01-03 16:18:02.201 [DEBUG] [caldav.internal.job.EventReloaderJob] - No periods exist for event 'grau'
2019-01-03 16:18:02.202 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - event is already in event map and newer -> delete the old one, reschedule timer
2019-01-03 16:18:02.207 [TRACE] [caldav.internal.job.EventReloaderJob] - after load events : oldeventids contains : []
2019-01-03 16:18:02.210 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-00h7nbqf19d8vsm9acsri3k025@google.com(grau@05.01.2019/00:00-06.01.2019/00:00)-0 - [groupName] : event-end - Sun Jan 06 00:00:00 CET 2019
2019-01-03 16:18:02.211 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-end-46ufn1aev77jrtjlvca6if3lrr@google.com(gelb@09.01.2019/00:00-10.01.2019/00:00)-0 - [groupName] : event-end - Thu Jan 10 00:00:00 CET 2019
2019-01-03 16:18:02.213 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : Abfall - [groupName] : event-reloader - Thu Jan 03 17:17:32 CET 2019
2019-01-03 16:18:02.218 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-46ufn1aev77jrtjlvca6if3lrr@google.com(gelb@09.01.2019/00:00-10.01.2019/00:00)-0 - [groupName] : event-start - Wed Jan 09 00:00:00 CET 2019
2019-01-03 16:18:02.219 [DEBUG] [caldav.internal.job.EventReloaderJob] - [job] : event-start-00h7nbqf19d8vsm9acsri3k025@google.com(grau@05.01.2019/00:00-06.01.2019/00:00)-0 - [groupName] : event-start - Sat Jan 05 00:00:00 CET 2019

I still can´t figure out why the binding always uses an old entry instead of the new entry that´s just 2 days ahead.

Any ideas how to get the caldav binding to recognize the correct entry?

kind regards
Michael

H102 · January 3, 2019, 3:43pm

Have you tried removing the binding, cleaning the cache, rebooting/restarting then reinstall the binding?

For apt-get install:

sudo systemctl stop openhab2

sudo openhab-cli clean-cache

sudo reboot
or if you prefer not to reboot
sudo systemctl start openhab2

Bredmich · January 3, 2019, 3:46pm

Yes, i already tried that.
Is there any beta version for a newer caldav binding?
The bindings i have installed are version 1.12.0.

H102 · January 3, 2019, 3:50pm

There is a 1.14.0 for caldav personal.
https://openhab.jfrog.io/openhab/libs-pullrequest-local/org/openhab/binding/org.openhab.binding.caldav-personal/

And caldev command 1.14.0 here.
https://openhab.jfrog.io/openhab/libs-pullrequest-local/org/openhab/binding/org.openhab.binding.caldav-command/

Bredmich · January 3, 2019, 4:00pm

Ok that seems to be a solution!

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

The caldav binding needs the caldavio to work!
org.openhab.io.caldav
org.openhab.binding.caldav-command

After reloading every entry the correct entry for 05.01.2019 was choosed and is now correctly displayed.

@H102 Is there any central newsfeed to check if a new version for a binding is released as stable or snapshot?

kind regards
Michael

H102 · January 3, 2019, 4:09pm

Glad you got it working again.

If there is I’m not aware of it.

Bredmich · January 3, 2019, 10:28pm

I checked the source in Github and the 1.14.0 bindings doesn´t have any changes (atleast the one i´m using) and it´s just a preparation for the next development cycle.
The only way seems to set a watch on Github

H102 · January 5, 2019, 4:38pm

@Bredmich while reading a new topic I remembered your question above and thought I would share the info.

Bredmich · January 6, 2019, 3:37pm

Ok and it´s still hanging at the old entry for the 05.01.2019 instead of the new entry for 09.01.2019…

H102 · January 6, 2019, 3:43pm

Try removing the binding, cleaning the cache, and restarting OH without the binding installed. Also check to make sure there are no caldev.cfg file that still exist. Then stop OH and re-install the new jar.

Bredmich · January 6, 2019, 3:51pm

This will help but i don´t know if it´s help for the next event.
It´s not a nice workaround to always reinstall the binding just to get the new entry.

Anyway, i´ll try it later.

H102 · January 6, 2019, 3:53pm

Agreed.

If the old info is still lingering then as a last ditch attempt you can delete /var/lib/openhab2/config/org/openhab/addons.config so it is regenerated with correct content to see if that helps.

Bredmich · January 6, 2019, 6:48pm

I stopped openHAB, deleted the addons.config and started openHAB again.

addons.config before:

action="telegram,mail"
binding="astro,exec,fritzboxtr0641,http1,icloud,ntp,tado,yamahareceiver,zwave,homematic,expire1,weather1,hue"
legacy=B"true"
misc="restdocs,openhabcloud"
package="expert"
persistence=",jdbc-mysql"
remote=B"true"
service.pid="org.openhab.addons"
transformation="map,regex,xslt,exec,javascript,scale,xpath,jsonpath"
ui="classic,basic,paper,habpanel,habmin"

addons.config now:

package="expert"
service.pid="org.openhab.addons"

caldav is still using the old entry…

Bredmich · January 6, 2019, 10:26pm

I had another look at the logs and the caldav bindings imports every event in the calender.
I had the understanding that preloadTime=14400 will only import events that are due in the next 10 days.

@namraccr as @H102 already asked you in another thread.
Do you have any idea how to fix my caldav configuration?

kind regards
Michael

namraccr · January 6, 2019, 11:21pm

As far as I can tell, your config is fine. Have you tried deleting the cached calendar file?

Bredmich · January 6, 2019, 11:36pm

Yes i deleted single entries and the whole folder.
Currently the binding doesn‘t load any event into the items.

Bredmich · January 7, 2019, 12:21pm

Now caldav is throwing some exceptions…

2019-01-07 12:21:24.978 [ERROR] [org.quartz.core.JobRunShell         ] - Job event-reloader.Abfall 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) ~[?:?]
	at java.util.ServiceLoader.access$300(ServiceLoader.java:185) ~[?:?]
	at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376) ~[?:?]
	at java.util.ServiceLoader$LazyIterator.access$700(ServiceLoader.java:323) ~[?:?]
	at java.util.ServiceLoader$LazyIterator$2.run(ServiceLoader.java:407) ~[?:?]
	at java.security.AccessController.doPrivileged(Native Method) ~[?:?]
	at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:409) ~[?:?]
	at java.util.ServiceLoader$1.next(ServiceLoader.java:480) ~[?:?]
	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:339) ~[?:?]
	at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:320) ~[?:?]
	at org.openhab.io.caldav.internal.job.EventReloaderJob.execute(EventReloaderJob.java:137) ~[?:?]
	at org.quartz.core.JobRunShell.run(JobRunShell.java:202) [107:org.eclipse.smarthome.core.scheduler:0.10.0.oh240]
	at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573) [107:org.eclipse.smarthome.core.scheduler:0.10.0.oh240]
2019-01-07 12:21:25.047 [ERROR] [org.quartz.core.ErrorLogger         ] - Job (event-reloader.Abfall threw an exception.
org.quartz.SchedulerException: Job threw an unhandled exception.
	at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [107:org.eclipse.smarthome.core.scheduler:0.10.0.oh240]
	at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573) [107:org.eclipse.smarthome.core.scheduler:0.10.0.oh240]
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) ~[?:?]
	at java.util.ServiceLoader.access$300(ServiceLoader.java:185) ~[?:?]
	at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376) ~[?:?]
	at java.util.ServiceLoader$LazyIterator.access$700(ServiceLoader.java:323) ~[?:?]
	at java.util.ServiceLoader$LazyIterator$2.run(ServiceLoader.java:407) ~[?:?]
	at java.security.AccessController.doPrivileged(Native Method) ~[?:?]
	at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:409) ~[?:?]
	at java.util.ServiceLoader$1.next(ServiceLoader.java:480) ~[?:?]
	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:339) ~[?:?]
	at org.openhab.io.caldav.internal.job.EventReloaderJob.loadEvents(EventReloaderJob.java:320) ~[?:?]
	at org.openhab.io.caldav.internal.job.EventReloaderJob.execute(EventReloaderJob.java:137) ~[?:?]
	at org.quartz.core.JobRunShell.run(JobRunShell.java:202) ~[?:?]
	... 1 more

This was and is a very bad idea!
If you installed all bindings through PaperUI, they get uninstalled when deleting the addons.config

Looks like there was an issue two years ago!

I´ve got no idea what the binding is doing…
Now it´s trying to find entries between now and null…

2019-01-07 14:03:14.331 [TRACE] [.io.caldav.internal.CalDavLoaderImpl] - Querying events for filter: CalDavQuery [calendarIds=[Abfall], from=2019-01-07T14:03:14.326+01:00, to=null, sort=ASCENDING, filterName=null]
2019-01-07 14:03:14.345 [DEBUG] [.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[Abfall], from=2019-01-07T14:03:14.326+01:00, to=null, sort=ASCENDING, filterName=null] with 0 entries

Bredmich · January 7, 2019, 3:52pm

I played around with the console and figured out that the two bindings personal and command are not working together.
The io.caldav always started without a problem but then personal or command couldn´t start and printed a ton of errors.
The bindings were stuck in Waiting and doesn´t really started.
After stopping and uninstalling the caldav-command binding the caldav-personal started and directly worked through all events.
This solved the problem and the caldav-personal was able to choose the correct calender entry.

Now i´m waiting for the switch to the next entry…

namraccr · January 8, 2019, 12:53am

This is the usual behaviour.

namraccr · January 8, 2019, 3:24am

For example:

21:17:27.161 [TRACE] [b.io.caldav.internal.CalDavLoaderImpl] - Querying events for filter: CalDavQuery [calendarIds=[myCalendar], from=2019-01-07T21:17:27.161-05:00, to=null, sort=ASCENDING, filterName=null]
21:17:27.161 [TRACE] [b.io.caldav.internal.CalDavLoaderImpl] - processing filter category
21:17:27.161 [TRACE] [b.io.caldav.internal.CalDavLoaderImpl] - processing event category
21:17:27.161 [TRACE] [b.io.caldav.internal.CalDavLoaderImpl] - filter-category encountered
21:17:27.161 [DEBUG] [b.io.caldav.internal.CalDavLoaderImpl] - return event list for CalDavQuery [calendarIds=[myCalendar], from=2019-01-07T21:17:27.161-05:00, to=null, sort=ASCENDING, filterName=null] with 1 entries
21:17:27.161 [DEBUG] [.caldav.internal.job.EventReloaderJob] - jobs scheduled :
21:17:27.161 [DEBUG] [.caldav.internal.job.EventReloaderJob] - [job] : event-end-31irki5pjm939066eg0rm2m5d7@google.com(God Rest Ye Merry Gentlemen@08.01.2019/13:00-08.01.2019/13:30)-0 - [groupName] : event-end - Tue Jan 08 13:30:00 EST 2019
21:17:27.161 [DEBUG] [.caldav.internal.job.EventReloaderJob] - [job] : myCalendar - [groupName] : event-reloader - Mon Jan 07 23:17:19 EST 2019
21:17:27.161 [DEBUG] [.caldav.internal.job.EventReloaderJob] - [job] : event-start-31irki5pjm939066eg0rm2m5d7@google.com(God Rest Ye Merry Gentlemen@08.01.2019/13:00-08.01.2019/13:30)-0 - [groupName] : event-start - Tue Jan 08 13:00:00 EST 2019