So, I upgraded to OH 4.0.4 aund rebooted. It worked well for about 7 days, but since yesterday the events are again missing … It’s weird.
I think I will terminate using astro binding.
Hi all,
Unfortunately, I also face an issue with astro:sun:local after upgrading to openHAB 4.2.0 on an Raspberry Pi 4 with openHABian.
I do not use the events for a longer time now but switched to a DateTime Item.
However, I use e.g. the Azimut or the Angle. And since the openHAB update, it sporadically stops to send updates:
At around 3:30, the Biding stopped sending new values. At around 14:40, I re-initialized the Thing. When I enabled it again, it sent the actual values, but then stopped again updating the values.
I do not see any issues regarding this binding in the log file.
When I disable the Thing, it looks like this:
2024-08-22 15:05:23.533 [DEBUG] [o.internal.handler.AstroThingHandler] - Disposing thing astro:sun:local
2024-08-22 15:05:23.533 [DEBUG] [o.internal.handler.AstroThingHandler] - Stopping scheduled jobs for thing astro:sun:local
2024-08-22 15:05:23.534 [DEBUG] [o.internal.handler.AstroThingHandler] - Thing astro:sun:local disposed
2024-08-22 15:05:23.538 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : ServiceFactory.ungetService()
2024-08-22 15:05:23.539 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : DependencyManager: osgi.ds.satisfying.condition close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@1170b029 at tracking count 1 refpairs: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-08-22 15:05:23.529 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'astro:sun:local' changed from ONLINE to UNINITIALIZED
2024-08-22 15:05:23.540 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'astro:sun:local' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)
When I then enable it, I see this in the logfile:
2024-08-22 15:07:44.686 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : ServiceFactory.getService()
2024-08-22 15:07:44.687 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : This thread collected dependencies
2024-08-22 15:07:44.688 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : getService (ServiceFactory) dependencies collected.
2024-08-22 15:07:44.688 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : Querying state active
2024-08-22 15:07:44.689 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-08-22 15:07:44.689 [DEBUG] [g.astro.internal.action.AstroActions] - Astro actions service instanciated
2024-08-22 15:07:44.690 [DEBUG] [g.astro.internal.action.AstroActions] - bundle org.openhab.binding.astro:4.2.0 (280)[org.openhab.binding.astro.internal.action.AstroActions(333)] : Changed state from active to active
2024-08-22 15:07:44.762 [DEBUG] [o.internal.handler.AstroThingHandler] - Initializing thing astro:sun:local
2024-08-22 15:07:44.764 [DEBUG] [o.internal.handler.AstroThingHandler] - org.openhab.binding.astro.internal.config.AstroThingConfig@1d3e6c66
2024-08-22 15:07:44.765 [DEBUG] [o.internal.handler.AstroThingHandler] - Restarting jobs for thing astro:sun:local
2024-08-22 15:07:44.766 [DEBUG] [o.internal.handler.AstroThingHandler] - Stopping scheduled jobs for thing astro:sun:local
2024-08-22 15:07:44.768 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled org.openhab.core.internal.scheduler.SchedulerImpl$ScheduledCompletableFutureRecurring@71a26413[Not completed, 2 dependents] at midnight
2024-08-22 15:07:44.775 [DEBUG] [o.internal.handler.AstroThingHandler] - Publishing planet Sun for thing astro:sun:local
2024-08-22 15:07:44.781 [DEBUG] [enhab.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:sun:local
2024-08-22 15:07:44.801 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/set#event/START in 18615218ms (at 2024-08-22T20:18:00)
2024-08-22 15:07:44.802 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/set#event/END in 18855198ms (at 2024-08-22T20:22:00)
2024-08-22 15:07:44.803 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/night#event/START in 26415197ms (at 2024-08-22T22:28:00)
2024-08-22 15:07:44.806 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/astroDusk#event/START in 23475195ms (at 2024-08-22T21:39:00)
2024-08-22 15:07:44.807 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/astroDusk#event/END in 26415194ms (at 2024-08-22T22:28:00)
2024-08-22 15:07:44.808 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/nauticDusk#event/START in 20895193ms (at 2024-08-22T20:56:00)
2024-08-22 15:07:44.808 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/nauticDusk#event/END in 23475192ms (at 2024-08-22T21:39:00)
2024-08-22 15:07:44.810 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/civilDusk#event/START in 18855190ms (at 2024-08-22T20:22:00)
2024-08-22 15:07:44.811 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/civilDusk#event/END in 20895189ms (at 2024-08-22T20:56:00)
2024-08-22 15:07:44.812 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/eveningNight#event/START in 26415188ms (at 2024-08-22T22:28:00)
2024-08-22 15:07:44.814 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Event job astro:sun:local/daylight#event/END in 18615187ms (at 2024-08-22T20:18:00)
2024-08-22 15:07:44.818 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Publish planet job astro:sun:local in 31935184ms (at 2024-08-22T23:59:59)
2024-08-22 15:07:44.834 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Sun phase job astro:sun:local/SUN_SET in 18615181ms (at 2024-08-22T20:18:00)
2024-08-22 15:07:44.836 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Sun phase job astro:sun:local/NIGHT in 26415164ms (at 2024-08-22T22:28:00)
2024-08-22 15:07:44.837 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Sun phase job astro:sun:local/ASTRO_DUSK in 23475163ms (at 2024-08-22T21:39:00)
2024-08-22 15:07:44.838 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Sun phase job astro:sun:local/NAUTIC_DUSK in 20895162ms (at 2024-08-22T20:56:00)
2024-08-22 15:07:44.839 [DEBUG] [o.internal.handler.AstroThingHandler] - Scheduled Sun phase job astro:sun:local/CIVIL_DUSK in 18855161ms (at 2024-08-22T20:22:00)
2024-08-22 15:07:44.841 [INFO ] [o.internal.handler.AstroThingHandler] - Scheduled Positional job astro:sun:local every 300 seconds
2024-08-22 15:07:44.842 [DEBUG] [o.internal.handler.AstroThingHandler] - Thing astro:sun:local initialized ONLINE
2024-08-22 15:07:44.756 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'astro:sun:local' changed from UNINITIALIZED (DISABLED) to INITIALIZING
2024-08-22 15:07:44.766 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'astro:sun:local' changed from INITIALIZING to ONLINE
Interesting is
2024-08-22 15:07:44.841 [INFO ] [o.internal.handler.AstroThingHandler] - Scheduled Positional job astro:sun:local every 300 seconds
But this Scheduler does not work anymore reliably.
After re-starting the openHAB service (not the Pi) it works again (at least for some time).
Any ideas how I can improve this situation?