[oH3] iCalendar: Binding wont load new events and always reschedules to the next day

Bredmich · January 13, 2022, 6:48pm

Hi there,

i was pretty happy to see the iCalendar binding for oH3 as the CalDav bindings never really worked for me.
Most of the times the binding stopped loading new events from Google without any logs.
Now the iCalendar binding is doing the same.

My local disposal company has a .ics calendar that can be downloaded with all upcoming pickup dates.
This worked fine for two month and now the binding stopped loading new events.
I checked the URL manually, downloaded the .ics file and can see new events.

Bridge:
URL http://www.edg.de/ical/kalender.ics?Strasse=<street>&Hausnummer=<housenumber>&Erinnerung=6&Abfallart=1,2,3,4
Update intercal 1440 minutes
Maximum file size 16 MB

Filter:
3 Events
1440 minutes update interval
Start 1 day
End 14 days

I changed the log to DEBUG but can´t see any obvious error.
That´s a full cycle after restarting the bundle through the Karaf console.

2022-01-13 19:22:49.013 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Querying state active
2022-01-13 19:22:49.015 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Deactivating component
2022-01-13 19:22:49.017 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : registration change queue [unregistered]
2022-01-13 19:22:49.061 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Querying state active
2022-01-13 19:22:49.062 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : getting deactivate: deactivate
2022-01-13 19:22:49.064 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Locating method deactivate in class org.openhab.binding.icalendar.internal.ICalendarHandlerFactory
2022-01-13 19:22:49.066 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Declared Method org.openhab.binding.icalendar.internal.ICalendarHandlerFactory.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2022-01-13 19:22:49.068 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Locating method deactivate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2022-01-13 19:22:49.070 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Found deactivate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.deactivate(org.osgi.service.component.ComponentContext)
2022-01-13 19:22:49.071 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : invoking deactivate: deactivate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2022-01-13 19:22:49.073 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : invoked deactivate: deactivate
2022-01-13 19:22:49.075 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : DependencyManager: $002 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@9478a6 at tracking count 3 refpairs: [[RefPair: ref: [{org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=51.48707024100108,7.5139349699020395, service.id=185, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.i18n.I18nProviderImpl, language=de, service.config.label=Regional Settings, measurementSystem=SI, component.id=26, timezone=Europe/Berlin, service.config.category=system, region=DE, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]}] service: [org.openhab.core.internal.i18n.I18nProviderImpl@19dd880]]]
2022-01-13 19:22:49.076 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@9478a6 at tracking count 2 refpairs: [[RefPair: ref: [{org.openhab.core.events.EventPublisher}={service.id=184, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25}] service: [org.openhab.core.internal.events.OSGiEventPublisher@1293bf5]]]
2022-01-13 19:22:49.078 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@9478a6 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=284, service.bundleid=176, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=148}] service: [org.openhab.core.io.net.http.internal.WebClientFactoryImpl@6ed8ab]]]
2022-01-13 19:22:49.079 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2022-01-13 19:22:49.080 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Changed state from active to satisfied
2022-01-13 19:22:49.082 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Deactivating dependency managers
2022-01-13 19:22:49.084 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Disabling dependency managers
2022-01-13 19:22:49.085 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : unregistering service listener for dependency $000
2022-01-13 19:22:49.087 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : unregistering service listener for dependency $001
2022-01-13 19:22:49.088 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : unregistering service listener for dependency $002
2022-01-13 19:22:49.089 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Querying state satisfied
2022-01-13 19:22:49.090 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Changed state from satisfied to disposed
2022-01-13 19:22:49.091 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(378)] : Disposing component (reason: 6)
2022-01-13 19:22:49.170 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2022-01-13 19:22:49.174 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.io.net.http.HttpClientFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2022-01-13 19:22:49.176 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Dependency Manager created $001interface=org.openhab.core.events.EventPublisher, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=1
2022-01-13 19:22:49.177 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Dependency Manager created $002interface=org.openhab.core.i18n.TimeZoneProvider, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=2
2022-01-13 19:22:49.178 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.icalendar.internal.ICalendarHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.icalendar]
2022-01-13 19:22:49.179 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2022-01-13 19:22:49.181 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Component Properties: {}
2022-01-13 19:22:49.182 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Querying state disabled
2022-01-13 19:22:49.183 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Querying state disabled
2022-01-13 19:22:49.184 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Component can not be activated since it is in state disabled
2022-01-13 19:22:49.185 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory] : Querying state disabled
2022-01-13 19:22:49.186 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Updating target filters
2022-01-13 19:22:49.188 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : No change in target property for dependency $000: currently registered: false
2022-01-13 19:22:49.189 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] :  No existing service listener to unregister for dependency $000
2022-01-13 19:22:49.190 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Setting target property for dependency $000 to null
2022-01-13 19:22:49.192 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.net.http.HttpClientFactory), initialReferenceFilter (objectClass=org.openhab.core.io.net.http.HttpClientFactory)
2022-01-13 19:22:49.193 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $000 tracker reset (closed)
2022-01-13 19:22:49.196 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=284, service.bundleid=176, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=148} (enter)
2022-01-13 19:22:49.198 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-01-13 19:22:49.199 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=284, service.bundleid=176, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=148} (exit)
2022-01-13 19:22:49.201 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $000 tracker opened
2022-01-13 19:22:49.202 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : registering service listener for dependency $000
2022-01-13 19:22:49.203 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : No change in target property for dependency $001: currently registered: false
2022-01-13 19:22:49.205 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] :  No existing service listener to unregister for dependency $001
2022-01-13 19:22:49.206 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Setting target property for dependency $001 to null
2022-01-13 19:22:49.207 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.events.EventPublisher), initialReferenceFilter (objectClass=org.openhab.core.events.EventPublisher)
2022-01-13 19:22:49.208 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $001 tracker reset (closed)
2022-01-13 19:22:49.211 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.events.EventPublisher}={service.id=184, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25} (enter)
2022-01-13 19:22:49.213 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2022-01-13 19:22:49.214 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.events.EventPublisher}={service.id=184, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25} (exit)
2022-01-13 19:22:49.215 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $001 tracker opened
2022-01-13 19:22:49.216 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : registering service listener for dependency $001
2022-01-13 19:22:49.217 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : No change in target property for dependency $002: currently registered: false
2022-01-13 19:22:49.218 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] :  No existing service listener to unregister for dependency $002
2022-01-13 19:22:49.219 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Setting target property for dependency $002 to null
2022-01-13 19:22:49.221 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.i18n.TimeZoneProvider), initialReferenceFilter (objectClass=org.openhab.core.i18n.TimeZoneProvider)
2022-01-13 19:22:49.222 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $002 tracker reset (closed)
2022-01-13 19:22:49.225 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=51.48707024100108,7.5139349699020395, service.id=185, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.i18n.I18nProviderImpl, language=de, service.config.label=Regional Settings, measurementSystem=SI, component.id=26, timezone=Europe/Berlin, service.config.category=system, region=DE, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]} (enter)
2022-01-13 19:22:49.227 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $002 tracking 3 SingleStatic active: false trackerOpened: false optional: false
2022-01-13 19:22:49.228 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=51.48707024100108,7.5139349699020395, service.id=185, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.i18n.I18nProviderImpl, language=de, service.config.label=Regional Settings, measurementSystem=SI, component.id=26, timezone=Europe/Berlin, service.config.category=system, region=DE, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]} (exit)
2022-01-13 19:22:49.229 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : dm $002 tracker opened
2022-01-13 19:22:49.230 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : registering service listener for dependency $002
2022-01-13 19:22:49.231 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Changed state from disabled to unsatisfiedReference
2022-01-13 19:22:49.232 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Component enabled
2022-01-13 19:22:49.233 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : ActivateInternal
2022-01-13 19:22:49.234 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Querying state unsatisfiedReference
2022-01-13 19:22:49.235 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Querying state unsatisfiedReference
2022-01-13 19:22:49.236 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Activating component from state unsatisfiedReference
2022-01-13 19:22:49.237 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Querying state unsatisfiedReference
2022-01-13 19:22:49.239 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Querying state unsatisfiedReference
2022-01-13 19:22:49.240 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Changed state from unsatisfiedReference to satisfied
2022-01-13 19:22:49.241 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : registration change queue [registered]
2022-01-13 19:22:49.245 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Checking constructor public org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.events.EventPublisher,org.openhab.core.i18n.TimeZoneProvider)
2022-01-13 19:22:49.246 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getReferenceClass: Looking for interface class org.openhab.core.io.net.http.HttpClientFactory through loader of org.openhab.binding.icalendar.internal.ICalendarHandlerFactory
2022-01-13 19:22:49.247 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getParameterClass: Found class org.openhab.core.io.net.http.HttpClientFactory
2022-01-13 19:22:49.248 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getReferenceClass: Looking for interface class org.openhab.core.events.EventPublisher through loader of org.openhab.binding.icalendar.internal.ICalendarHandlerFactory
2022-01-13 19:22:49.249 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getParameterClass: Found class org.openhab.core.events.EventPublisher
2022-01-13 19:22:49.250 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getReferenceClass: Looking for interface class org.openhab.core.i18n.TimeZoneProvider through loader of org.openhab.binding.icalendar.internal.ICalendarHandlerFactory
2022-01-13 19:22:49.251 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getParameterClass: Found class org.openhab.core.i18n.TimeZoneProvider
2022-01-13 19:22:49.252 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Found constructor with 3 arguments : public org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.events.EventPublisher,org.openhab.core.i18n.TimeZoneProvider)
2022-01-13 19:22:49.253 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : This thread collected dependencies
2022-01-13 19:22:49.254 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getService (single component manager) dependencies collected.
2022-01-13 19:22:49.255 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Querying state satisfied
2022-01-13 19:22:49.256 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Querying state satisfied
2022-01-13 19:22:49.257 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=284, service.bundleid=176, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=148}] service: [null]]]
2022-01-13 19:22:49.258 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.events.EventPublisher}={service.id=184, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.events.OSGiEventPublisher, component.id=25}] service: [null]]]
2022-01-13 19:22:49.259 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : For dependency $002, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.i18n.TranslationProvider, org.openhab.core.i18n.LocaleProvider, org.openhab.core.i18n.LocationProvider, org.openhab.core.i18n.TimeZoneProvider, org.openhab.core.i18n.UnitProvider}={location=51.48707024100108,7.5139349699020395, service.id=185, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.i18n.I18nProviderImpl, language=de, service.config.label=Regional Settings, measurementSystem=SI, component.id=26, timezone=Europe/Berlin, service.config.category=system, region=DE, service.config.description.uri=system:i18n, service.pid=[org.openhab.i18n, org.openhab.i18n]}] service: [null]]]
2022-01-13 19:22:49.261 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : getting activate: activate
2022-01-13 19:22:49.262 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Locating method activate in class org.openhab.binding.icalendar.internal.ICalendarHandlerFactory
2022-01-13 19:22:49.263 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Declared Method org.openhab.binding.icalendar.internal.ICalendarHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-01-13 19:22:49.264 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2022-01-13 19:22:49.265 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2022-01-13 19:22:49.266 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2022-01-13 19:22:49.268 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : invoked activate: activate
2022-01-13 19:22:49.269 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Set implementation object for component
2022-01-13 19:22:49.270 [DEBUG] [dar.internal.ICalendarHandlerFactory] - bundle org.openhab.binding.icalendar:3.2.0 (254)[org.openhab.binding.icalendar.internal.ICalendarHandlerFactory(379)] : Changed state from satisfied to active

2022-01-13 19:22:49.024 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from ONLINE to UNINITIALIZED
2022-01-13 19:22:49.043 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2022-01-13 19:22:49.046 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from ONLINE to UNINITIALIZED
2022-01-13 19:22:49.062 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2022-01-13 19:22:51.820 [DEBUG] [ar.internal.handler.ICalendarHandler] - Scheduled reschedule in 1 day
2022-01-13 19:22:51.825 [DEBUG] [.internal.handler.EventFilterHandler] - Ignoring call for updating states as this instance is not initialized yet.

2022-01-13 19:22:51.808 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2022-01-13 19:22:51.817 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from INITIALIZING to ONLINE
2022-01-13 19:22:51.829 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2022-01-13 19:22:51.848 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from INITIALIZING to UNKNOWN
2022-01-13 19:22:51.856 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNKNOWN to ONLINE

Some examples from the .ics file
The .ics file only has 42kB

BEGIN:VEVENT
CLASS:PUBLIC
DTEND;VALUE=DATE:20220115
DTSTAMP:20220113T180249Z
DTSTART;VALUE=DATE:20220114
LOCATION:<address>
PRIORITY:5
SEQUENCE:0
SUMMARY:Wertstoffsammlung
UID:<UID>
BEGIN:VALARM
ACTION:DISPLAY
DESCRIPTION:Erinnerung Wertstoffsammlung
TRIGGER:-PT6H
UID:<UID>
X-WR-ALARMUID:<UID>
END:VALARM
END:VEVENT

BEGIN:VEVENT
CLASS:PUBLIC
DTEND;VALUE=DATE:20220121
DTSTAMP:20220113T180249Z
DTSTART;VALUE=DATE:20220120
LOCATION:<address>
PRIORITY:5
SEQUENCE:0
SUMMARY:Papiersammlung
UID:<UID>
BEGIN:VALARM
ACTION:DISPLAY
DESCRIPTION:Erinnerung Papiersammlung
TRIGGER:-PT6H
UID:<UID>
X-WR-ALARMUID:<UID>
END:VALARM
END:VEVENT

So there are clearly events in the scope of my filter.

Items:

String edgEvent0Title "Erste Müllsorte [%s]" {channel="icalendar:eventfilter:edg:edg:result_0#title"}
DateTime edgEvent0Start "Start [%1$tT, %1$tY-%1$tm-%1$td]" {channel="icalendar:eventfilter:edg:edg:result_0#begin"}
DateTime edgEvent0End "Ende [%1$tT, %1$tY-%1$tm-%1$td]" {channel="icalendar:eventfilter:edg:edg:result_0#end"}

String edgEvent1Title "Zweite Müllsorte [%s]" {channel="icalendar:eventfilter:edg:edg:result_1#title"}
DateTime edgEvent1Start "Start [%1$tT, %1$tY-%1$tm-%1$td]" {channel="icalendar:eventfilter:edg:edg:result_1#begin"}
DateTime edgEvent1End "Ende [%1$tT, %1$tY-%1$tm-%1$td]" {channel="icalendar:eventfilter:edg:edg:result_1#end"}

String edgEvent2Title "Dritte Müllsorte [%s]" {channel="icalendar:eventfilter:edg:edg:result_2#title"}
DateTime edgEvent2Start "Start [%1$tT, %1$tY-%1$tm-%1$td]" {channel="icalendar:eventfilter:edg:edg:result_2#begin"}
DateTime edgEvent2End "Ende [%1$tT, %1$tY-%1$tm-%1$td]" {channel="icalendar:eventfilter:edg:edg:result_2#end"}

All of them have the state UNDEF.

Maybe @damihe has an idea?

kind regards
Michael

Bredmich · January 19, 2022, 6:02pm

Without changing anything since i made this thread, the binding started to import new events today.
I´m still confused why the binding stopped and now resumed to import events.

Let´s see for how long it works now.

fba1 · February 24, 2022, 11:23am

Since yesterday I’m facing the same issue … " Ignoring call for updating states as this instance is not initialized yet." . I tried to restart openhab after a cache cleaning… Always the same behaviour… I hope it will fix itself by mystery in a couple of days …

Bredmich · April 16, 2022, 9:43am

I never found a solution and had to wait until the issue resolved by mystery…

alfista2600 · January 17, 2023, 1:08pm

I am having a similar issue. I have one bridge and five event filters. The bridge eventually updated its next event, but none of the event filters updated. All show the last event. About to go turn on logging and maybe reboot.

happenpappen · January 10, 2024, 1:46pm

I have the same problem - maybe this binding has problems with the turn of the year?

Bredmich · January 10, 2024, 2:10pm

Mine stopped working too…
It stopped on the 27.December 2023.
I already checked the link and i can download the .ics file in my browser.
I removed and re-added the thing and it´s still not loading the entries.

2024-01-10 14:59:02.001 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from ONLINE to UNINITIALIZED
2024-01-10 14:59:02.012 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2024-01-10 14:59:02.014 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from ONLINE to UNINITIALIZED
2024-01-10 14:59:02.023 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)
2024-01-10 14:59:02.024 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2024-01-10 14:59:03.714 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from UNINITIALIZED (DISABLED) to INITIALIZING
2024-01-10 14:59:03.723 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from INITIALIZING to ONLINE
2024-01-10 14:59:03.738 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2024-01-10 14:59:03.786 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from INITIALIZING to UNKNOWN
2024-01-10 14:59:03.813 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentSwitch' updated to OFF
2024-01-10 14:59:03.814 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentEventName' updated to UNDEF
2024-01-10 14:59:03.816 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentEventStart' updated to UNDEF
2024-01-10 14:59:03.817 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentEventEnd' updated to UNDEF
2024-01-10 14:59:03.823 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgNextEventName' updated to UNDEF
2024-01-10 14:59:03.824 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgNextEventStart' updated to UNDEF
2024-01-10 14:59:03.825 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgNextEventEnd' updated to UNDEF
2024-01-10 14:59:03.827 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgLastUpdate' updated to 2024-01-10T14:42:20.004+0100
2024-01-10 14:59:03.834 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNKNOWN to ONLINE
2024-01-10 14:59:03.840 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent0Title' updated to UNDEF
2024-01-10 14:59:03.841 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent0Start' updated to UNDEF
2024-01-10 14:59:03.843 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent0End' updated to UNDEF
2024-01-10 14:59:03.846 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent1Title' updated to UNDEF
2024-01-10 14:59:03.848 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent1Start' updated to UNDEF
2024-01-10 14:59:03.850 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent1End' updated to UNDEF
2024-01-10 14:59:03.850 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent2Title' updated to UNDEF
2024-01-10 14:59:03.851 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent2Start' updated to UNDEF
2024-01-10 14:59:03.851 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent2End' updated to UNDEF
2024-01-10 15:02:27.304 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from ONLINE to REMOVING
2024-01-10 15:02:27.306 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from REMOVING to REMOVED
2024-01-10 15:02:27.331 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from ONLINE to UNINITIALIZED
2024-01-10 15:02:27.353 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2024-01-10 15:02:27.354 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from REMOVED to UNINITIALIZED
2024-01-10 15:02:27.372 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2024-01-10 15:03:06.237 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from UNINITIALIZED to INITIALIZING
2024-01-10 15:03:06.246 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:calendar:edg' changed from INITIALIZING to ONLINE
2024-01-10 15:03:06.264 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2024-01-10 15:03:06.351 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentSwitch' updated to OFF
2024-01-10 15:03:06.351 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentEventName' updated to UNDEF
2024-01-10 15:03:06.354 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentEventStart' updated to UNDEF
2024-01-10 15:03:06.355 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgCurrentEventEnd' updated to UNDEF
2024-01-10 15:03:06.359 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgNextEventName' updated to UNDEF
2024-01-10 15:03:06.360 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgNextEventStart' updated to UNDEF
2024-01-10 15:03:06.364 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgNextEventEnd' updated to UNDEF
2024-01-10 15:03:06.366 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgLastUpdate' updated to 2024-01-10T14:42:20.004+0100
2024-01-10 15:03:06.511 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from INITIALIZING to UNKNOWN
2024-01-10 15:03:06.511 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'icalendar:eventfilter:edg:edg' changed from UNKNOWN to ONLINE
2024-01-10 15:03:06.522 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent0Title' updated to UNDEF
2024-01-10 15:03:06.523 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent0Start' updated to UNDEF
2024-01-10 15:03:06.527 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent0End' updated to UNDEF
2024-01-10 15:03:06.528 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent1Title' updated to UNDEF
2024-01-10 15:03:06.529 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent1Start' updated to UNDEF
2024-01-10 15:03:06.529 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent1End' updated to UNDEF
2024-01-10 15:03:06.530 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent2Title' updated to UNDEF
2024-01-10 15:03:06.530 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent2Start' updated to UNDEF
2024-01-10 15:03:06.533 [INFO ] [openhab.event.ItemStateUpdatedEvent ] - Item 'edgEvent2End' updated to UNDEF