Log opened at Thu, May 05 2022 01:34:31 UTC (actual time)
Current log level is Normal
ACP Expert Scheduler Engine Version 8.3.2
Part of ACP Expert Release 8.3
Full license: Arne Henden
2022-05-05 01:34:31.7: REAL TIME internal clock initialized at 05-May-2022 01:34:31 UTC
2022-05-05 01:34:31.7: Dispatcher started at 05-May-2022 01:34:31 UTC
2022-05-05 01:34:32.4: Loaded Constraint plugin AirMass
2022-05-05 01:34:32.4: Loaded Constraint plugin AirmassRange
2022-05-05 01:34:32.5: Loaded Constraint plugin Horizon
2022-05-05 01:34:32.5: Loaded Constraint plugin HourAngle
2022-05-05 01:34:32.6: Loaded Constraint plugin MoonAvoid
2022-05-05 01:34:32.6: Loaded Constraint plugin MoonDown
2022-05-05 01:34:32.7: Loaded Constraint plugin SkyCondition
2022-05-05 01:34:32.7: Loaded Constraint plugin TimeRange
2022-05-05 01:34:33.6: 196 plans are now pending.
2022-05-05 01:34:33.7: Attach ACP sequencer
2022-05-05 01:34:40.9: Weather successfully connected. Wait for valid data...
2022-05-05 01:34:41.4: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2022-05-05 01:34:41.4: Sequencer does sky flats
2022-05-05 01:34:46.3: ++ Weather Safe ++
2022-05-05 01:34:46.6: HTTP command listener failed to start: Access is denied
2022-05-05 01:34:46.6:   If this is Windows 8-10, you will need to add permission (ACL) to listen on port 9123.
2022-05-05 01:34:46.6:   See Scheduler Help > Configuring the Program > Noteworthy Configuration Options, at the bottom.
2022-05-05 01:46:22.5: ++ Observatory Startup ++
2022-05-05 01:46:22.5: Weather safety is now disabled
2022-05-05 01:46:22.5: Start ACP Sequencer's StartupObs script
2022-05-05 01:47:13.3: Weather safety is re-enabled
2022-05-05 01:47:13.3: Weather safety is now disabled
2022-05-05 01:47:13.3: Open the observatory dome/roof
2022-05-05 01:48:14.1: Weather safety is re-enabled
2022-05-05 01:48:14.1: Observatory startup succeeded, and dome opened successfully
2022-05-05 01:48:14.1: ++ Dusk Sky Flats ++
2022-05-05 01:48:14.1: Start ACP AutoFlat script for Dusk flats
2022-05-05 02:32:13.7: ++ Auto Focus ++
2022-05-05 02:32:13.8: Doing initial autofocus.
2022-05-05 02:32:13.8: Start special ACP AutoFocus script for scheduler
2022-05-05 02:37:43.8: Next periodic autofocus ASAP after 3600sec. 05-May-2022 03:37:24 UTC
2022-05-05 02:37:52.0: Plan HD168418 has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan MN84 has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan NSV_11154 has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan NSV_11749 has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan PNV_17291350 has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan U_Sco has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan V1687_Cyg has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan V5631_Sgr has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.0: Plan V884_Her has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:52.1: Plan V1674_Her has Monitor Mode. Time to resubmit it.
2022-05-05 02:37:53.1: Plan Melotte_111 deferred, too early
2022-05-05 02:37:53.5: Plan NGC_7790 deferred, too early
2022-05-05 02:37:55.0: Dispatcher cycle time: 11.2 sec.
2022-05-05 02:37:55.0: Acquire data for Observation U_Leo...
2022-05-05 02:37:55.0:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan U_Leo)
2022-05-05 02:37:55.1: Send Observation U_Leo to ACP Sequencer
2022-05-05 02:49:15.8: Acquisition time: 680.7791278 sec.
2022-05-05 02:49:15.8: Data for Observation U_Leo of Plan U_Leo acquired successfully.
2022-05-05 02:49:15.9: Plan U_Leo completed successfully.
2022-05-05 02:49:15.9: Image Efficiency: 69.4%
2022-05-05 02:49:15.9: Cycle Efficiency: 98.4%
2022-05-05 02:49:16.8: Dispatcher cycle time: 0.8 sec.
2022-05-05 02:49:16.8: Acquire data for Observation GZ_Cnc...
2022-05-05 02:49:16.8:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan GZ_Cnc)
2022-05-05 02:49:16.8: Send Observation GZ_Cnc to ACP Sequencer
2022-05-05 03:10:14.7: Acquisition time: 1257.8665044 sec.
2022-05-05 03:10:14.7: Data for Observation GZ_Cnc of Plan GZ_Cnc acquired successfully.
2022-05-05 03:10:14.7: Plan GZ_Cnc completed successfully.
2022-05-05 03:10:14.7: Image Efficiency: 76.3%
2022-05-05 03:10:14.7: Cycle Efficiency: 99.9%
2022-05-05 03:10:15.6: Dispatcher cycle time: 0.7 sec.
2022-05-05 03:10:15.6: Acquire data for Observation J072816...
2022-05-05 03:10:15.6:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan J072816)
2022-05-05 03:10:15.6: Send Observation J072816 to ACP Sequencer
2022-05-05 03:30:08.4: ACP data acquisition failed for Observation J072816.
2022-05-05 03:30:08.4:   (Observation J072816 interrupted by acquisition process failure.)
2022-05-05 03:30:09.1: Dispatcher cycle time: 0.6 sec.
2022-05-05 03:30:09.1: Acquire data for Observation IR_Com...
2022-05-05 03:30:09.1:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan IR_Com)
2022-05-05 03:30:09.2: Send Observation IR_Com to ACP Sequencer
2022-05-05 03:39:55.8: ACP data acquisition failed for Observation IR_Com.
2022-05-05 03:39:55.8:   (Observation IR_Com interrupted by acquisition process failure.)
2022-05-05 03:39:55.9: ++ Auto Focus ++
2022-05-05 03:39:55.9: Doing periodic autofocus.
2022-05-05 03:39:55.9: Start special ACP AutoFocus script for scheduler
2022-05-05 03:42:09.4: ** ACP script "AutoFocusScheduler" failed:
2022-05-05 03:42:09.4: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 81 column 2.

2022-05-05 03:42:09.4: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2022-05-05 03:42:09.8: **EXCEPTION IN SCHEDULER:
2022-05-05 03:42:09.8: The telescope is not connected.
2022-05-05 03:42:10.0: Traceback:
   at ACP.TelescopeClass.get_RightAscension()
   at DC3.Scheduler.ACPSequencer.get_CurrentRA()
   at DC3.Scheduler.Engine.Efficiency(Plan P, DateTime t)
   at DC3.Scheduler.Engine.SelectBestPlan(ArrayList PList)
   at DC3.Scheduler.Engine.DoSchedulePass()
   at DC3.Scheduler.Engine.Run()
2022-05-05 03:42:10.1: No operator intervention command tool specified
2022-05-05 03:42:11.2: Run statistics:
2022-05-05 03:42:11.2:   Observations:   273
2022-05-05 03:42:11.2:   Considered:     7
2022-05-05 03:42:11.2:   Completed:      0
2022-05-05 03:42:11.2:   Skipped:        0
2022-05-05 03:42:11.2:   Never Eligible: 266
2022-05-05 03:42:11.2:   Failed:         7
2022-05-05 03:42:11.2: Shutter-Open efficiency: 38.59%
2022-05-05 03:42:11.2: Overall Efficiency: 99.64%
2022-05-05 03:42:11.2: Release ACP sequencer
Log closed at Thu, May 05 2022 03:42:13 UTC (actual time)