CalDav no Events Found

caldavbinding
Tags: #<Tag:0x00007f6ce68c6360>

(Andrew Pawelski) #1

I had caldavio and caldav_personal working fine but has suddenly gone to getting the error:

No events found in event cache for item 'CalendarName1'. Attempting to load from calendar.
08-Jan-2018 20:57:49.233 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarName1, setting to UNDEF

even when I add a test calendar entry it still never finds it
Anyone know how I can get more info or fix?


(namraccr) #2

I find with CalDav setting the bindings to TRACE mode is best for debugging.


(Andrew Pawelski) #3

That’s where I got the log from I have posted no other info

Thanks
Andrew


(namraccr) #4

Exactly the problem. Much more info is needed if anyone is to be able to help you.

You should start with your CalDav configurations.

And as I said, you need to set the bindings into TRACE mode and check the logs for messages. You can post the logs here if you need help determining which parts are important.


(Andrew Pawelski) #5

OK sorry the no was mean to be on.

Here are my binding configs:

CaldavIO

caldavio:AJ:url=https://p42-caldav.icloud.com:443/13xxx3642/calendars/086xxx6-1a0b-4301-a99d-5c5ebbcc5079/
caldavio:AJ:username=xxx@xxx.com.au
caldavio:AJ:password=mypassword
caldavio:AJ:reloadInterval=30
caldavio:AJ:preloadTime=2000
caldavio:AJ:disableCertificateVerification=false
caldavio:timeZone=Australia/Adelaide

CaldavPersonal

caldavPersonal:usedCalendars=AJ

items

alendarName1   "Up Next [%s]"     <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:1 value:NAME" } 
DateTime CalendarTimeStart1 "Starts [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:1 value:START" } 
DateTime CalendarTimeEnd1 "Ends [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:1 value:END" }
//String CalendarAt1 "At [%s]"  <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:1 value:PLACE" } 
String CalendarName2   "Coming up [%s]"     <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:2 value:NAME" } 
DateTime CalendarTimeStart2 "Starts [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:2 value:START" } 
DateTime CalendarTimeEnd2 "Ends [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:2 value:END" }
String CalendarName3   "Later  [%s]"     <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:3 value:NAME" } 
DateTime CalendarTimeStart3 "Starts [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:3 value:START" } 
DateTime CalendarTimeEnd3 "Ends [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:3 value:END" }
String CalendarName4   "Later [%s]"     <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:4 value:NAME" } 
DateTime CalendarTimeStart4 "Starts [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:4 value:START" } 
DateTime CalendarTimeEnd4 "Ends [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:4 value:END" }
//String CalendarAt4 "At [%s]"  <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:4 value:PLACE" } 
String CalendarName5   "Later [%s]"     <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:5 value:NAME" } 
DateTime CalendarTimeStart5 "Starts [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:5 value:START" } 
DateTime CalendarTimeEnd5 "Ends [%1$ta, %1$te %1$tb %1$tl:%1$tM%1$tp]"         <calendar>  { caldavPersonal="calendar:AJ type:EVENT eventNr:5 value:END" }

org.ops4j.pax.logging.cfg

# caldav_personal
log4j2.logger.caldav_personal.name = org.openhab.binding.caldav_personal
log4j2.logger.caldav_personal.level = TRACE
log4j2.logger.caldav_personal.additivity = false
log4j2.logger.caldav_personal.appenderRefs = caldav_personal
log4j2.logger.caldav_personal.appenderRef.caldav_personal.ref = caldav_personal
log4j2.appender.caldav_personal.name = caldav_personal
log4j2.appender.caldav_personal.type = RollingRandomAccessFile
log4j2.appender.caldav_personal.fileName = /var/log/openhab2/caldav_personal.log
log4j2.appender.caldav_personal.filePattern = /var/log/openhab2/caldav_personal.log.%i
log4j2.appender.caldav_personal.immediateFlush = true
log4j2.appender.caldav_personal.append = true
log4j2.appender.caldav_personal.layout.type = PatternLayout
log4j2.appender.caldav_personal.layout.pattern = %d{dd-MMM-yyyy HH:mm:ss.SSS} [%-5.5p] [%-50.50c] - %m%n
log4j2.appender.caldav_personal.policies.type = Policies
log4j2.appender.caldav_personal.policies.size.type = SizeBasedTriggeringPolicy
log4j2.appender.caldav_personal.policies.size.size = 100MB
log4j2.appender.caldav_personal.strategy.type = DefaultRolloverStrategy

sample log

09-Jan-2018 22:18:38.377 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - calendar reloaded: AJ
09-Jan-2018 22:18:38.378 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - No events found in event cache for item 'CalendarTimeStart2'. Attempting to load from calendar.
09-Jan-2018 22:18:38.379 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeStart2, setting to UNDEF
09-Jan-2018 22:18:38.380 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarName3, setting to UNDEF
09-Jan-2018 22:18:38.383 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeStart1, setting to UNDEF
09-Jan-2018 22:18:38.386 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarName4, setting to UNDEF
09-Jan-2018 22:18:38.389 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarName1, setting to UNDEF
09-Jan-2018 22:18:38.391 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarName2, setting to UNDEF
09-Jan-2018 22:18:38.392 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeStart5, setting to UNDEF
09-Jan-2018 22:18:38.394 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeStart4, setting to UNDEF
09-Jan-2018 22:18:38.395 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarName5, setting to UNDEF
09-Jan-2018 22:18:38.397 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeStart3, setting to UNDEF
09-Jan-2018 22:18:38.398 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeEnd4, setting to UNDEF
09-Jan-2018 22:18:38.399 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeEnd5, setting to UNDEF
09-Jan-2018 22:18:38.401 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeEnd1, setting to UNDEF
09-Jan-2018 22:18:38.402 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeEnd2, setting to UNDEF
09-Jan-2018 22:18:38.403 [DEBUG] [hab.binding.caldav_personal.internal.CalDavBinding] - no event found for CalendarTimeEnd3, setting to UNDEF

Even though I have set TRACE it seems to be showing DEBUG.
I have all the ICS files in etc/caldav/AJ
I have tried clearing the cache and temp files but I recall there might be cache in the karaf database - havent worked out how to purge this.

Hope this helps explain the prob a bit better.


(namraccr) #6

You need to set up logging for caldavio also (org.openhab.io.caldav). That’s what does all of the calendar loading.


(Andrew Pawelski) #7

OK I didnt include it because the log file is blank but I do already have this set up:

# caldav_io
log4j2.logger.caldav_io.name = org.openhab.binding.caldav_io
log4j2.logger.caldav_io.level = TRACE
log4j2.logger.caldav_io.additivity = false
log4j2.logger.caldav_io.appenderRefs = caldav_io
log4j2.logger.caldav_io.appenderRef.caldav_io.ref = caldav_io
log4j2.appender.caldav_io.name = caldav_io
log4j2.appender.caldav_io.type = RollingRandomAccessFile
log4j2.appender.caldav_io.fileName = /var/log/openhab2/caldav_io.log
log4j2.appender.caldav_io.filePattern = /var/log/openhab2/caldav_io.log.%i
log4j2.appender.caldav_io.immediateFlush = true
log4j2.appender.caldav_io.append = true
log4j2.appender.caldav_io.layout.type = PatternLayout
log4j2.appender.caldav_io.layout.pattern = %d{dd-MMM-yyyy HH:mm:ss.SSS} [%-5.5p] [%-50.50c] - %m%n
log4j2.appender.caldav_io.policies.type = Policies
log4j2.appender.caldav_io.policies.size.type = SizeBasedTriggeringPolicy
log4j2.appender.caldav_io.policies.size.size = 100MB
log4j2.appender.caldav_io.strategy.type = DefaultRolloverStrategy

(Andrew Pawelski) #8

forgot to include Im running openHAB 2.3.0 Build #1165


(Andrew Pawelski) #9

update - I had the wrong name for the caldavio logging - changed to org.openhab.io.caldav and now getting log files - will review


(Andrew Pawelski) #10

i used to have three calendars running now only 1 as above but logs are showing the three are still being used - do i need to remove something from karaf?

Should be only “AJ”

12-Jan-2018 16:20:50.667 [DEBUG] [rg.openhab.io.caldav.internal.job.EventReloaderJob] - [job] : Birthdays - [groupName] : event-reloader - Fri Jan 12 16:32:28 ACDT 2018
12-Jan-2018 16:20:50.668 [DEBUG] [rg.openhab.io.caldav.internal.job.EventReloaderJob] - [job] : OFC - [groupName] : event-reloader - Fri Jan 12 16:21:48 ACDT 2018
12-Jan-2018 16:20:50.668 [DEBUG] [rg.openhab.io.caldav.internal.job.EventReloaderJob] - [job] : AJ - [groupName] : event-reloader - Fri Jan 12 16:32:38 ACDT 2018

(SiHui) #11

Maybe you got catched by the “cache” bug: look into your /userdata/config/org/openhab folder for any *.config files related to your setup, if they show any difference you can delete them safely, then restart openHAB, they will be recreated during startup - hopefully with the correct content then.


(Andrew Pawelski) #12

I dont have this folder? only /userdata/config/org/apache/karaf and there is only one .config file in there called jass.config


(SiHui) #13

And I don’t know your setup :rofl:

Take a look at the file locations whatever setup you are running (mine is a manual setup):

https://docs.openhab.org/installation/linux.html#file-locations


(Andrew Pawelski) #14

well there is definately something cached as i removed the binding and reinstalled and then the log shows the old configs are still running

Should only have AJ below here as thats all there is in the config file (but used to have the other two)
Also - in this line shows from now to null - shouldnt this be a future date?

Querying events for filter: CalDavQuery [calendarIds=[AJ], from=2018-01-13T02:20:24.707+10:30, to=null, sort=ASCENDING, filterName=null]
13-Jan-2018 02:20:24.715 [DEBUG] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - return event list for CalDavQuery [calendarIds=[AJ], from=2018-01-13T02:20:24.707+10:30, to=null, sort=ASCENDING, filterName=null] with 0 entries
ServiceEvent REGISTERED - {org.osgi.service.cm.ManagedService, org.openhab.io.caldav.CalDavLoader}={service.pid=org.openhab.caldavio, component.name=org.openhab.io.caldav.caldavloader, component.id=237, service.id=392, service.bundleid=243, service.scope=bundle} - org.openhab.io.caldav
13-Jan-2018 02:20:23.403 [DEBUG] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - Update was called for CalDAV IO.
13-Jan-2018 02:20:23.415 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: AJ:disableCertificateVerification
13-Jan-2018 02:20:23.423 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: AJ:password
13-Jan-2018 02:20:23.424 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: AJ:preloadTime
13-Jan-2018 02:20:23.425 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: AJ:reloadInterval
13-Jan-2018 02:20:23.427 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: AJ:url
13-Jan-2018 02:20:23.428 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: AJ:username
13-Jan-2018 02:20:23.429 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: Birthdays:disableCertificateVerification
13-Jan-2018 02:20:23.430 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: Birthdays:password
13-Jan-2018 02:20:23.431 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: Birthdays:preloadTime
13-Jan-2018 02:20:23.432 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: Birthdays:reloadInterval
13-Jan-2018 02:20:23.434 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: Birthdays:url
13-Jan-2018 02:20:23.435 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: Birthdays:username
13-Jan-2018 02:20:23.436 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: OFC:disableCertificateVerification
13-Jan-2018 02:20:23.437 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: OFC:password
13-Jan-2018 02:20:23.438 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: OFC:preloadTime
13-Jan-2018 02:20:23.439 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: OFC:reloadInterval
13-Jan-2018 02:20:23.440 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: OFC:url
13-Jan-2018 02:20:23.441 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: OFC:username
13-Jan-2018 02:20:23.457 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - processing configuration parameter: timeZone

(SiHui) #15

As I said: remove caldavio.config and caldavCommand.config (or caldavPersonal.config) and restart openHAB …


(Andrew Pawelski) #16

sorry i edited my post just as you replied - I dont have an openhab folder in that location only a karaf one im using the distro install
/userdata/config/org/apache/karaf


(SiHui) #17

I know … and I can’t tell you the exact folder because I am running a manual install, sorry :joy:
Use Linux commands to find the mentioned files with the .config ending … don’t delete your .cfg files, though.


(Andrew Pawelski) #18

ok found it and that cleaned it up for anyone else found here:

/var/lib/openhab2/config/org/openhab

now still have the undef issue and i am wondering if it is because the from - to line is showing to null

[job] : AJ - [groupName] : event-reloader - Sat Jan 13 02:50:08 ACDT 2018
13-Jan-2018 02:50:00.115 [TRACE] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - Querying events for filter: CalDavQuery [calendarIds=[AJ], from=2018-01-13T02:50:00.112+10:30, to=null, sort=ASCENDING, filterName=null]
13-Jan-2018 02:50:00.118 [DEBUG] [org.openhab.io.caldav.internal.CalDavLoaderImpl   ] - return event list for CalDavQuery [calendarIds=[AJ], from=2018-01-13T02:50:00.112+10:30, to=null, sort=ASCENDING, filterName=null] with 0 entries

(SiHui) #19

Did you wait long enough for the

reloadInterval=xx

to occur?


(Andrew Pawelski) #20

Yes set this down to 2