Log opened at Thu, Dec 15 2022 01:37:35 UTC (actual time)
Current log level is Normal
ACP Expert Scheduler Engine Version 9.0.4
Part of ACP Expert 9.0 Release
Full license: Arne Henden
2022-12-15 01:37:35.9: REAL TIME internal clock initialized at 15-Dec-2022 01:37:35 UTC
2022-12-15 01:37:35.9: Dispatcher started at 15-Dec-2022 01:37:35 UTC
2022-12-15 01:37:35.9: Loaded Constraint plugin AirMass
2022-12-15 01:37:35.9: Loaded Constraint plugin AirmassRange
2022-12-15 01:37:35.9: Loaded Constraint plugin Horizon
2022-12-15 01:37:35.9: Loaded Constraint plugin HourAngle
2022-12-15 01:37:35.9: Loaded Constraint plugin MoonAvoid
2022-12-15 01:37:35.9: Loaded Constraint plugin MoonDown
2022-12-15 01:37:35.9: Loaded Constraint plugin SkyCondition
2022-12-15 01:37:35.9: Loaded Constraint plugin TimeRange
2022-12-15 01:37:36.7: 4 plans are now pending.
2022-12-15 01:37:36.7: Attach ACP sequencer
2022-12-15 01:37:42.9: ++ Weather Safe ++
2022-12-15 01:37:43.9: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2022-12-15 01:37:43.9: Sequencer does sky flats
2022-12-15 01:37:48.0: ++ Observatory Startup ++
2022-12-15 01:37:48.0: Weather safety is now disabled
2022-12-15 01:37:48.0: Start ACP Sequencer's StartupObs script
2022-12-15 01:38:03.1: Weather safety is re-enabled
2022-12-15 01:38:03.1: Weather safety is now disabled
2022-12-15 01:38:03.1: Open the observatory dome/roof
2022-12-15 01:39:03.8: Weather safety is re-enabled
2022-12-15 01:39:03.8: Observatory startup succeeded, and dome opened successfully
2022-12-15 01:39:03.8: Periodic AF schedule reset. Interval is now 1.0 hours
2022-12-15 01:39:03.8: Last AutoFocus was completed at 15-Dec-2022 01:21:25 UTC
2022-12-15 01:39:03.8: Next AutoFocus in 42.4 minutes or ASAP after that.
2022-12-15 01:39:03.9: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 01:39:03.9:     247 min. remaining to meet strict constraints.
2022-12-15 01:39:03.9:     498 min. remaining for the plan to fit before dawn.
2022-12-15 01:39:03.9:     but only 93 min till start so Plan transits mid-way in its time span.
2022-12-15 01:39:03.9:     deferred (rising) for 74 min, until 15-Dec-2022 02:54:03 UTC
2022-12-15 01:39:03.9: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 01:39:03.9:     247 min. remaining to meet strict constraints.
2022-12-15 01:39:03.9:     405 min. remaining for the plan to fit before dawn.
2022-12-15 01:39:03.9:     but only 47 min till start so Plan transits mid-way in its time span.
2022-12-15 01:39:03.9:     deferred (rising) for 19 min, until 15-Dec-2022 01:58:33 UTC
2022-12-15 01:39:03.9: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 01:39:03.9:     247 min. remaining to meet strict constraints.
2022-12-15 01:39:03.9:     313 min. remaining for the plan to fit before dawn.
2022-12-15 01:39:03.9:     but only 1 min till start so Plan transits mid-way in its time span.
2022-12-15 01:39:03.9:     ending rising plan delay. Plan is now eligible.
2022-12-15 01:39:03.9: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 01:39:03.9:     247 min. remaining to meet strict constraints.
2022-12-15 01:39:03.9:     220 min. remaining for the plan to fit before dawn.
2022-12-15 01:39:03.9:     but only -45 min till start so Plan transits mid-way in its time span.
2022-12-15 01:39:03.9:     ending rising plan delay. Plan is now eligible.
2022-12-15 01:39:04.0: Dispatcher cycle time: 0.2 sec.
2022-12-15 01:39:04.0: Acquire data for Observation TIC35022727...
2022-12-15 01:39:04.0:   (belongs to Project Transit, Plan TIC35022727)
2022-12-15 01:39:04.0: Send Observation TIC35022727 to ACP Sequencer
2022-12-15 02:16:16.7: ACP data acquisition failed for Observation TIC35022727.
2022-12-15 02:16:16.7:   (Observation TIC35022727 interrupted by acquisition process failure.)
2022-12-15 02:16:16.9: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 02:16:16.9:     210 min. remaining to meet strict constraints.
2022-12-15 02:16:16.9:     368 min. remaining for the plan to fit before dawn.
2022-12-15 02:16:16.9:     but only 10 min till start so Plan transits mid-way in its time span.
2022-12-15 02:16:16.9:     ending rising plan delay. Plan is now eligible.
2022-12-15 02:16:16.9: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 02:16:16.9:     210 min. remaining to meet strict constraints.
2022-12-15 02:16:16.9:     276 min. remaining for the plan to fit before dawn.
2022-12-15 02:16:16.9:     but only -36 min till start so Plan transits mid-way in its time span.
2022-12-15 02:16:16.9:     ending rising plan delay. Plan is now eligible.
2022-12-15 02:16:16.9: Dispatcher cycle time: 0.0 sec.
2022-12-15 02:16:16.9: Acquire data for Observation TIC35022727...
2022-12-15 02:16:16.9:   (belongs to Project Transit, Plan TIC35022727)
2022-12-15 02:16:16.9: Send Observation TIC35022727 to ACP Sequencer
2022-12-15 02:40:13.0: ACP data acquisition failed for Observation TIC35022727.
2022-12-15 02:40:13.0:   (Observation TIC35022727 interrupted by acquisition process failure.)
2022-12-15 02:40:13.1: ++ Auto Focus ++
2022-12-15 02:40:13.2: Doing periodic autofocus.
2022-12-15 02:40:13.2: Start special ACP AutoFocus script for scheduler
2022-12-15 02:44:19.4: Next periodic autofocus ASAP after 3600sec. 15-Dec-2022 03:43:59 UTC
2022-12-15 02:44:19.4: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 02:44:19.4:     182 min. remaining to meet strict constraints.
2022-12-15 02:44:19.4:     248 min. remaining for the plan to fit before dawn.
2022-12-15 02:44:19.4:     but only -64 min till start so Plan transits mid-way in its time span.
2022-12-15 02:44:19.4:     ending rising plan delay. Plan is now eligible.
2022-12-15 02:44:19.4: Dispatcher cycle time: 0.0 sec.
2022-12-15 02:44:19.4: Acquire data for Observation TIC35022727...
2022-12-15 02:44:19.4:   (belongs to Project Transit, Plan TIC35022727)
2022-12-15 02:44:19.4: Send Observation TIC35022727 to ACP Sequencer
2022-12-15 02:53:21.7: ACP data acquisition failed for Observation TIC35022727.
2022-12-15 02:53:21.7:   (Observation TIC35022727 interrupted by acquisition process failure.)
2022-12-15 02:54:22.6: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 02:54:22.6:     172 min. remaining to meet strict constraints.
2022-12-15 02:54:22.6:     422 min. remaining for the plan to fit before dawn.
2022-12-15 02:54:22.6:     but only 18 min till start so Plan transits mid-way in its time span.
2022-12-15 02:54:22.6:     ending rising plan delay. Plan is now eligible.
2022-12-15 02:54:22.6: Dispatcher cycle time: 0.0 sec.
2022-12-15 02:54:22.6: Acquire data for Observation TIC35022727...
2022-12-15 02:54:22.6:   (belongs to Project Transit, Plan TIC35022727)
2022-12-15 02:54:22.6: Send Observation TIC35022727 to ACP Sequencer
2022-12-15 03:01:49.1: ACP data acquisition failed for Observation TIC35022727.
2022-12-15 03:01:49.1:   (Observation TIC35022727 interrupted by acquisition process failure.)
2022-12-15 03:13:59.8: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 03:13:59.8:     152 min. remaining to meet strict constraints.
2022-12-15 03:13:59.8:     403 min. remaining for the plan to fit before dawn.
2022-12-15 03:13:59.8:     but only -1 min till start so Plan transits mid-way in its time span.
2022-12-15 03:13:59.8:     ending rising plan delay. Plan is now eligible.
2022-12-15 03:13:59.8: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 03:13:59.8:     152 min. remaining to meet strict constraints.
2022-12-15 03:13:59.8:     310 min. remaining for the plan to fit before dawn.
2022-12-15 03:13:59.8:     but only -47 min till start so Plan transits mid-way in its time span.
2022-12-15 03:13:59.8:     ending rising plan delay. Plan is now eligible.
2022-12-15 03:13:59.8: Rising plan delay enabled for Plan TIC35022727:
2022-12-15 03:13:59.8:     152 min. remaining to meet strict constraints.
2022-12-15 03:13:59.8:     218 min. remaining for the plan to fit before dawn.
2022-12-15 03:13:59.8:     but only -93 min till start so Plan transits mid-way in its time span.
2022-12-15 03:13:59.8:     ending rising plan delay. Plan is now eligible.
2022-12-15 03:13:59.8: Dispatcher cycle time: 0.1 sec.
2022-12-15 03:13:59.8: Acquire data for Observation TIC35022727...
2022-12-15 03:13:59.8:   (belongs to Project Transit, Plan TIC35022727)
2022-12-15 03:13:59.8: Send Observation TIC35022727 to ACP Sequencer
2022-12-15 04:31:10.9: **EXCEPTION IN SCHEDULER:
2022-12-15 04:31:10.9: ACP Has failed to acquire data more than 4 times in a row. Something is really wrong!
2022-12-15 04:31:10.9: Traceback:
   at DC3.Scheduler.Engine.DoSchedulePass()
   at DC3.Scheduler.Engine.Run()
2022-12-15 04:31:10.9: Weather safety is now disabled
2022-12-15 04:31:10.9: Close the observatory dome/roof.
2022-12-15 04:31:56.9: Weather safety is re-enabled
2022-12-15 04:31:56.9: No operator intervention command tool specified
2022-12-15 04:31:56.9: Run statistics:
2022-12-15 04:31:56.9:   Observations:   5
2022-12-15 04:31:56.9:   Considered:     0
2022-12-15 04:31:56.9:   Completed:      0
2022-12-15 04:31:56.9:   Skipped:        0
2022-12-15 04:31:56.9:   Never Eligible: 5
2022-12-15 04:31:56.9:   Failed:         0
2022-12-15 04:31:56.9: Shutter-Open efficiency: 0.00%
2022-12-15 04:31:56.9: Overall Efficiency: 99.90%
2022-12-15 04:31:56.9: Release ACP sequencer
2022-12-15 04:31:57.2: ACPSequencer: Failed to get ACP's DispatcherEnabled property. ACP is compromised.
2022-12-15 04:31:57.2:               Object reference not set to an instance of an object.
Log closed at Thu, Dec 15 2022 04:31:58 UTC (actual time)