Log opened at Fri, Dec 16 2022 22:09:52 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-17 12:07:46.7: Periodic AF schedule reset. Interval is now 1.0 hours
2022-12-17 12:07:46.7: Last AutoFocus was completed at 17-Dec-2022 11:51:44 UTC
2022-12-17 12:07:46.7: Next AutoFocus in 44.0 minutes or ASAP after that.
2022-12-17 12:07:46.7: Dispatcher cycle time: 0.1 sec.
2022-12-17 12:07:46.7: Acquire data for Observation GZ_Cnc...
2022-12-17 12:07:46.7:   (belongs to Project AAVSO_MISC_MZK_AUTO_ver2706, Plan GZ_Cnc)
2022-12-17 12:25:24.7: Acquisition time: 1058 sec.
2022-12-17 12:25:24.7: Data for Observation GZ_Cnc of Plan GZ_Cnc acquired successfully.
2022-12-17 12:25:24.7: Plan GZ_Cnc completed successfully.
2022-12-17 12:25:24.7: Image Efficiency: 90.7%
2022-12-17 12:25:24.7: Cycle Efficiency: 100.0%
2022-12-17 12:25:24.7: Dispatcher cycle time: 0.1 sec.
2022-12-17 12:25:24.7: Acquire data for Observation S5_0716+714...
2022-12-17 12:25:24.7:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan S5_0716+714)
2022-12-17 12:29:42.7: Acquisition time: 258 sec.
2022-12-17 12:29:42.7: Data for Observation S5_0716+714 of Plan S5_0716+714 acquired successfully.
2022-12-17 12:29:42.7: Plan S5_0716+714 completed successfully.
2022-12-17 12:29:42.7: Image Efficiency: 69.7%
2022-12-17 12:29:42.7: Cycle Efficiency: 100.0%
2022-12-17 12:29:42.7: Rising plan delay enabled for Plan IU_Leo:
2022-12-17 12:29:42.7:     247 min. remaining to meet strict constraints.
2022-12-17 12:29:42.7:     40 min. remaining for the plan to fit before dawn.
2022-12-17 12:29:42.7:     but only 0 min till start so Plan transits mid-way in its time span.
2022-12-17 12:29:42.7:     ending rising plan delay. Plan is now eligible.
2022-12-17 12:29:42.7: Rising plan delay enabled for Plan IU_Leo:
2022-12-17 12:29:42.7:     247 min. remaining to meet strict constraints.
2022-12-17 12:29:42.7:     40 min. remaining for the plan to fit before dawn.
2022-12-17 12:29:42.7:     but only 0 min till start so Plan transits mid-way in its time span.
2022-12-17 12:29:42.7:     ending rising plan delay. Plan is now eligible.
2022-12-17 12:29:42.7: Dispatcher cycle time: 0.1 sec.
2022-12-17 12:29:42.7: Acquire data for Observation IU_Leo...
2022-12-17 12:29:42.7:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver3087, Plan IU_Leo)
2022-12-17 12:43:02.7: Acquisition time: 800 sec.
2022-12-17 12:43:02.7: Data for Observation IU_Leo of Plan IU_Leo acquired successfully.
2022-12-17 12:43:02.7: Plan IU_Leo completed successfully.
2022-12-17 12:43:02.7: Image Efficiency: 90.0%
2022-12-17 12:43:02.7: Cycle Efficiency: 100.0%
2022-12-17 12:43:02.7: Dispatcher cycle time: 0.1 sec.
2022-12-17 12:43:02.7: Acquire data for Observation SU_Lyn...
2022-12-17 12:43:02.7:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan SU_Lyn)
2022-12-17 12:48:52.7: Acquisition time: 350 sec.
2022-12-17 12:48:52.7: Data for Observation SU_Lyn of Plan SU_Lyn acquired successfully.
2022-12-17 12:48:52.7: Plan SU_Lyn completed successfully.
2022-12-17 12:48:52.7: Image Efficiency: 62.0%
2022-12-17 12:48:52.7: Cycle Efficiency: 100.0%
2022-12-17 12:48:52.7: Rising plan delay enabled for Plan NSV-6690:
2022-12-17 12:48:52.7:     473 min. remaining to meet strict constraints.
2022-12-17 12:48:52.7:     1 min. remaining for the plan to fit before dawn.
2022-12-17 12:48:52.7:     ending rising plan delay. Plan is now eligible.
2022-12-17 12:48:52.7: Dispatcher cycle time: 0.1 sec.
2022-12-17 12:48:52.7: Acquire data for Observation U_Leo...
2022-12-17 12:48:52.7:   (belongs to Project AAVSO_MISC_SFY_AUTO_ver2706, Plan U_Leo)
2022-12-17 12:57:43.7: Acquisition time: 531 sec.
2022-12-17 12:57:43.7: Data for Observation U_Leo of Plan U_Leo acquired successfully.
2022-12-17 12:57:43.7: Plan U_Leo completed successfully.
2022-12-17 12:57:43.7: Image Efficiency: 90.4%
2022-12-17 12:57:43.7: Cycle Efficiency: 100.0%
2022-12-17 12:57:43.7: ++ Auto Focus ++
2022-12-17 12:57:43.7: Doing periodic autofocus.
2022-12-17 12:57:43.7: [sim] AutoFocus would start now
2022-12-17 13:00:43.7: [sim] Last AF time recorded
2022-12-17 13:00:43.7: Next periodic autofocus ASAP after 3600sec. 17-Dec-2022 14:00:43 UTC
2022-12-17 13:00:43.7: Dispatcher cycle time: 0.1 sec.
2022-12-17 13:00:43.7: Acquire data for Observation OI_158...
2022-12-17 13:00:43.7:   (belongs to Project AAVSO_MISC_GCO_AUTO_ver2706, Plan OI_158)
2022-12-17 13:13:44.7: Acquisition time: 781 sec.
2022-12-17 13:13:44.7: Data for Observation OI_158 of Plan OI_158 acquired successfully.
2022-12-17 13:13:44.7: Plan OI_158 completed successfully.
2022-12-17 13:13:44.7: Image Efficiency: 92.2%
2022-12-17 13:13:44.7: Cycle Efficiency: 100.0%
2022-12-17 13:13:44.7: Rising plan delay enabled for Plan Mrk_180:
2022-12-17 13:13:44.7:     383 min. remaining to meet strict constraints.
2022-12-17 13:13:44.7:     6 min. remaining for the plan to fit before dawn.
2022-12-17 13:13:44.7:     but only 0 min till start so Plan transits mid-way in its time span.
2022-12-17 13:13:44.7:     ending rising plan delay. Plan is now eligible.
2022-12-17 13:13:44.7: Rising plan delay enabled for Plan Mrk_180:
2022-12-17 13:13:44.7:     383 min. remaining to meet strict constraints.
2022-12-17 13:13:44.7:     6 min. remaining for the plan to fit before dawn.
2022-12-17 13:13:44.7:     but only 0 min till start so Plan transits mid-way in its time span.
2022-12-17 13:13:44.7:     ending rising plan delay. Plan is now eligible.
2022-12-17 13:13:44.7: Dispatcher cycle time: 0.0 sec.
2022-12-17 13:13:44.7: Acquire data for Observation Mrk_180...
2022-12-17 13:13:44.7:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan Mrk_180)
2022-12-17 13:18:02.7: Acquisition time: 258 sec.
2022-12-17 13:18:02.7: Data for Observation Mrk_180 of Plan Mrk_180 acquired successfully.
2022-12-17 13:18:02.7: Plan Mrk_180 completed successfully.
2022-12-17 13:18:02.7: Image Efficiency: 69.8%
2022-12-17 13:18:02.7: Cycle Efficiency: 100.0%
2022-12-17 13:18:02.7: Dispatcher cycle time: 0.0 sec.
2022-12-17 13:18:02.7: Acquire data for Observation Mrk_180...
2022-12-17 13:18:02.7:   (belongs to Project AAVSO_MISC_OAR_AUTO_ver2706, Plan Mrk_180)
2022-12-17 13:21:57.7: Acquisition time: 235 sec.
2022-12-17 13:21:57.7: Data for Observation Mrk_180 of Plan Mrk_180 acquired successfully.
2022-12-17 13:21:57.7: Plan Mrk_180 completed successfully.
2022-12-17 13:21:57.7: Image Efficiency: 76.6%
2022-12-17 13:21:57.7: Cycle Efficiency: 100.0%
2022-12-17 13:37:27.7: ++ Dawn Flats ++
2022-12-17 13:37:27.7: [sim] Dawn sky flat acquisition would now occur
2022-12-17 13:57:57.7: -- Observatory Shutdown --
2022-12-17 13:57:57.7: [sim] Disable weather unsafe interrupts
2022-12-17 13:57:57.7: [sim] Shutdown the observatory.
2022-12-17 14:02:57.7: [sim] Enable weather unsafe interrupts
2022-12-17 13:02:57.0: Simulated clock time changed to 17-Dec-2022 13:02:57 UTC
2022-12-17 13:02:57.0: Simulated clock time changed to 17-Dec-2022 13:02:57 UTC
2022-12-17 14:02:57.0: Simulated clock time changed to 17-Dec-2022 14:02:57 UTC
2022-12-17 14:02:57.0: Simulated clock time changed to 17-Dec-2022 14:02:57 UTC
2022-12-16 22:12:14.1: Clock changed to real-time
2022-12-16 22:12:14.1: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2022-12-16 22:12:14.4: Attach ACP sequencer
2022-12-17 14:02:57.0: Clock changed to simulated time
2022-12-16 22:12:15.7: Simulated clock time changed to 16-Dec-2022 22:12:15 UTC
2022-12-16 22:12:15.7: ==== Dispatcher is running in SIMULATION (TESTING) mode ====
2022-12-16 23:12:15.0: Simulated clock time changed to 16-Dec-2022 23:12:15 UTC
2022-12-16 23:12:15.0: Simulated clock time changed to 16-Dec-2022 23:12:15 UTC
2022-12-16 00:12:15.0: Simulated clock time changed to 16-Dec-2022 00:12:15 UTC
2022-12-16 00:12:15.0: Simulated clock time changed to 16-Dec-2022 00:12:15 UTC
2022-12-16 23:12:15.0: Simulated clock time changed to 16-Dec-2022 23:12:15 UTC
2022-12-16 23:12:15.0: Simulated clock time changed to 16-Dec-2022 23:12:15 UTC
2022-12-17 00:05:45.0: ++ Observatory Startup ++
2022-12-17 00:05:45.0: Weather safety is now disabled
2022-12-17 00:05:45.0: Start ACP Sequencer's StartupObs script
2022-12-17 00:05:45.0: Weather safety is re-enabled
2022-12-17 00:05:45.0: Weather safety is now disabled
2022-12-17 00:05:45.0: Open the observatory dome/roof
2022-12-17 00:05:45.0: Weather safety is re-enabled
2022-12-17 00:05:45.0: **EXCEPTION IN SCHEDULER:
2022-12-17 00:05:45.0: DOME FAILURE: Dome reports shutter error status
2022-12-17 00:05:45.0: Traceback:
   at DC3.Scheduler.ACPSequencer.get_DomeOpen()
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2022-12-17 00:05:45.0: Weather safety is now disabled
2022-12-17 00:05:45.0: Weather safety is re-enabled
2022-12-17 00:05:45.0: DOME/ROOF FAILURE: In addition, attempting to close your dome/roof this error occurred:
2022-12-17 00:05:45.0: DOME FAILURE: Dome reports shutter error status when asked to close.
2022-12-17 00:05:45.0: No operator intervention command tool specified
2022-12-16 00:05:45.0: Simulated clock time changed to 16-Dec-2022 00:05:45 UTC
2022-12-17 00:05:45.0: Simulated clock time changed to 17-Dec-2022 00:05:45 UTC
2022-12-17 00:05:45.0: Run statistics:
2022-12-17 00:05:45.0:   Observations:   299
2022-12-17 00:05:45.0:   Considered:     57
2022-12-17 00:05:45.0:   Completed:      55
2022-12-17 00:05:45.0:   Skipped:        0
2022-12-17 00:05:45.0:   Never Eligible: 242
2022-12-17 00:05:45.0:   Failed:         2
2022-12-17 00:05:45.0: Shutter-Open efficiency: 81.79%
2022-12-17 00:05:45.0: Overall Efficiency: 99.90%
2022-12-17 00:05:45.0: Release ACP sequencer
2022-12-17 00:05:45.0: ACPSequencer: Failed to get ACP's DispatcherEnabled property. ACP is compromised.
2022-12-17 00:05:45.0:               Object reference not set to an instance of an object.
2022-12-17 00:05:45.0: Simulated clock stopped
Log closed at Fri, Dec 16 2022 22:15:09 UTC (actual time)