Log opened at Thu, Dec 30 2021 00:09:15 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
2021-12-30 00:09:15.6: REAL TIME internal clock initialized at 30-Dec-2021 00:09:15 UTC
2021-12-30 00:09:15.6: Dispatcher started at 30-Dec-2021 00:09:15 UTC
2021-12-30 00:09:15.9: Loaded Constraint plugin AirMass
2021-12-30 00:09:16.0: Loaded Constraint plugin AirmassRange
2021-12-30 00:09:16.0: Loaded Constraint plugin Horizon
2021-12-30 00:09:16.0: Loaded Constraint plugin HourAngle
2021-12-30 00:09:16.1: Loaded Constraint plugin MoonAvoid
2021-12-30 00:09:16.1: Loaded Constraint plugin MoonDown
2021-12-30 00:09:16.2: Loaded Constraint plugin SkyCondition
2021-12-30 00:09:16.2: Loaded Constraint plugin TimeRange
2021-12-30 00:09:17.1: 182 plans are now pending.
2021-12-30 00:09:17.2: Attach ACP sequencer
2021-12-30 00:09:24.4: Weather successfully connected. Wait for valid data...
2021-12-30 00:09:25.3: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2021-12-30 00:09:25.4: Sequencer does sky flats
2021-12-30 00:09:29.8: HTTP command listener failed to start: Access is denied
2021-12-30 00:09:29.8:   If this is Windows 8-10, you will need to add permission (ACL) to listen on port 9123.
2021-12-30 00:09:29.8:   See Scheduler Help > Configuring the Program > Noteworthy Configuration Options, at the bottom.
2021-12-30 00:09:29.9: ++ Weather Safe ++
2021-12-30 00:11:36.7: ++ Observatory Startup ++
2021-12-30 00:11:36.7: Weather safety is now disabled
2021-12-30 00:11:36.7: Start ACP Sequencer's StartupObs script
2021-12-30 00:12:57.7: Weather safety is re-enabled
2021-12-30 00:12:57.7: Weather safety is now disabled
2021-12-30 00:12:57.7: Open the observatory dome/roof
2021-12-30 00:13:58.4: Weather safety is re-enabled
2021-12-30 00:13:58.4: Observatory startup succeeded, and dome opened successfully
2021-12-30 00:13:58.5: ++ Dusk Sky Flats ++
2021-12-30 00:13:58.5: Start ACP AutoFlat script for Dusk flats
2021-12-30 00:53:34.2: ++ Auto Focus ++
2021-12-30 00:53:34.3: Doing initial autofocus.
2021-12-30 00:53:34.3: Start special ACP AutoFocus script for scheduler
2021-12-30 00:59:18.2: Next periodic autofocus ASAP after 3600sec. 30-Dec-2021 01:58:59 UTC
2021-12-30 00:59:23.4: Plan MV_Lyr has Monitor Mode. Time to resubmit it.
2021-12-30 00:59:23.4: Plan CG_Dra has Monitor Mode. Time to resubmit it.
2021-12-30 00:59:23.4: Plan V1674_Her has Monitor Mode. Time to resubmit it.
2021-12-30 00:59:26.9: Dispatcher cycle time: 8.7 sec.
2021-12-30 00:59:26.9: Acquire data for Observation MV_Lyr...
2021-12-30 00:59:26.9:   (belongs to Project AAVSO_P233_DKS_AUTO_ver2623, Plan MV_Lyr)
2021-12-30 00:59:27.0: Send Observation MV_Lyr to ACP Sequencer
2021-12-30 01:34:42.8: Acquisition time: 2115.8336053 sec.
2021-12-30 01:34:42.8: Data for Observation MV_Lyr of Plan MV_Lyr acquired successfully.
2021-12-30 01:34:42.9: Plan MV_Lyr completed successfully.
2021-12-30 01:34:42.9: Image Efficiency: 84.7%
2021-12-30 01:34:42.9: Cycle Efficiency: 99.6%
2021-12-30 01:34:45.5: Dispatcher cycle time: 2.4 sec.
2021-12-30 01:34:45.5: Acquire data for Observation KU_Cyg...
2021-12-30 01:34:45.5:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan KU_Cyg)
2021-12-30 01:34:45.6: Send Observation KU_Cyg to ACP Sequencer
2021-12-30 02:53:21.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-30 02:54:43.8: ** ACP script "AcquireScheduler" failed:
2021-12-30 02:54:43.8: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 1160 column 17.

2021-12-30 02:54:43.8: RUN FAILED: Scheduler was stopped during run
2021-12-30 02:54:43.9: ACP data acquisition failed for Observation KU_Cyg.
2021-12-30 02:54:43.9:   (Observation KU_Cyg interrupted by operator or system.)
2021-12-30 02:56:29.8: ++ Observatory Startup ++
2021-12-30 02:56:29.8: Weather safety is now disabled
2021-12-30 02:56:29.8: Start ACP Sequencer's StartupObs script
2021-12-30 02:57:12.6: Weather safety is re-enabled
2021-12-30 02:57:12.6: Weather safety is now disabled
2021-12-30 02:57:12.6: Open the observatory dome/roof
2021-12-30 02:57:59.0: Weather safety is re-enabled
2021-12-30 02:57:59.0: Observatory startup succeeded, and dome opened successfully
2021-12-30 02:57:59.0: ++ Auto Focus ++
2021-12-30 02:57:59.0: Doing initial autofocus.
2021-12-30 02:57:59.0: Start special ACP AutoFocus script for scheduler
2021-12-30 03:03:34.2: Next periodic autofocus ASAP after 3600sec. 30-Dec-2021 04:03:19 UTC
2021-12-30 03:03:41.9: Dispatcher cycle time: 7.8 sec.
2021-12-30 03:03:41.9: Acquire data for Observation EE_Cep...
2021-12-30 03:03:41.9:   (belongs to Project AAVSO_P124_Staels_AUTO_ver2706, Plan EE_Cep)
2021-12-30 03:03:42.0: Send Observation EE_Cep to ACP Sequencer
2021-12-30 03:12:30.3: Acquisition time: 528.2930942 sec.
2021-12-30 03:12:30.3: Data for Observation EE_Cep of Plan EE_Cep acquired successfully.
2021-12-30 03:12:30.3: Plan EE_Cep completed successfully.
2021-12-30 03:12:30.3: Image Efficiency: 33.6%
2021-12-30 03:12:30.3: Cycle Efficiency: 98.5%
2021-12-30 03:12:32.0: Dispatcher cycle time: 1.6 sec.
2021-12-30 03:12:32.0: Acquire data for Observation SS_Cyg...
2021-12-30 03:12:32.0:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan SS_Cyg)
2021-12-30 03:12:32.1: Send Observation SS_Cyg to ACP Sequencer
2021-12-30 03:21:12.3: Acquisition time: 520.2191336 sec.
2021-12-30 03:21:12.3: Data for Observation SS_Cyg of Plan SS_Cyg acquired successfully.
2021-12-30 03:21:12.3: Plan SS_Cyg completed successfully.
2021-12-30 03:21:12.3: Image Efficiency: 57.5%
2021-12-30 03:21:12.3: Cycle Efficiency: 99.7%
2021-12-30 03:21:14.2: Dispatcher cycle time: 1.7 sec.
2021-12-30 03:21:14.2: Acquire data for Observation BO_Cep...
2021-12-30 03:21:14.2:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan BO_Cep)
2021-12-30 03:21:14.3: Send Observation BO_Cep to ACP Sequencer
2021-12-30 03:43:54.8: Acquisition time: 1360.4980771 sec.
2021-12-30 03:43:54.8: Data for Observation BO_Cep of Plan BO_Cep acquired successfully.
2021-12-30 03:43:54.9: Plan BO_Cep completed successfully.
2021-12-30 03:43:54.9: Image Efficiency: 69.7%
2021-12-30 03:43:54.9: Cycle Efficiency: 99.9%
2021-12-30 03:43:56.9: Dispatcher cycle time: 1.9 sec.
2021-12-30 03:43:56.9: Acquire data for Observation AZ_Cas...
2021-12-30 03:43:56.9:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan AZ_Cas)
2021-12-30 03:43:56.9: Send Observation AZ_Cas to ACP Sequencer
2021-12-30 03:46:55.7: Acquisition time: 178.7869822 sec.
2021-12-30 03:46:55.7: Data for Observation AZ_Cas of Plan AZ_Cas acquired successfully.
2021-12-30 03:46:55.7: Plan AZ_Cas completed successfully.
2021-12-30 03:46:55.7: Image Efficiency: 16.6%
2021-12-30 03:46:55.7: Cycle Efficiency: 98.9%
2021-12-30 03:46:57.6: Dispatcher cycle time: 1.7 sec.
2021-12-30 03:46:57.6: Acquire data for Observation GZ_Cet...
2021-12-30 03:46:57.6:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan GZ_Cet)
2021-12-30 03:46:57.6: Send Observation GZ_Cet to ACP Sequencer
2021-12-30 04:03:04.6: Acquisition time: 966.9993422 sec.
2021-12-30 04:03:04.6: Data for Observation GZ_Cet of Plan GZ_Cet acquired successfully.
2021-12-30 04:03:04.6: Plan GZ_Cet completed successfully.
2021-12-30 04:03:04.7: Image Efficiency: 74.3%
2021-12-30 04:03:04.7: Cycle Efficiency: 99.8%
2021-12-30 04:03:06.5: Dispatcher cycle time: 1.8 sec.
2021-12-30 04:03:06.5: Acquire data for Observation CGCS_5926...
2021-12-30 04:03:06.6:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan CGCS_5926)
2021-12-30 04:03:06.9: Send Observation CGCS_5926 to ACP Sequencer
2021-12-30 04:41:09.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-30 04:41:58.4: ** ACP script "AcquireScheduler" failed:
2021-12-30 04:41:58.5: **Script Error (Tracking has been stopped)**
Source:   ACP Observatory Control Software
Message:  
Location: line 1885 column 5.

2021-12-30 04:41:58.5: RUN FAILED: Scheduler was stopped during run
2021-12-30 04:41:58.6: ACP data acquisition failed for Observation CGCS_5926.
2021-12-30 04:41:58.6:   (Observation CGCS_5926 interrupted by operator or system.)
2021-12-30 04:43:50.3: -- Weather Unsafe --
2021-12-30 05:04:02.0: ++ Weather Safe ++
2021-12-30 05:04:03.1: ++ Observatory Startup ++
2021-12-30 05:04:03.1: Weather safety is now disabled
2021-12-30 05:04:03.1: Start ACP Sequencer's StartupObs script
2021-12-30 05:04:47.0: Weather safety is re-enabled
2021-12-30 05:04:47.1: Weather safety is now disabled
2021-12-30 05:04:47.1: Open the observatory dome/roof
2021-12-30 05:06:02.8: Weather safety is re-enabled
2021-12-30 05:06:02.8: Observatory startup succeeded, and dome opened successfully
2021-12-30 05:06:02.8: ++ Auto Focus ++
2021-12-30 05:06:02.8: Doing initial autofocus.
2021-12-30 05:06:02.8: Start special ACP AutoFocus script for scheduler
2021-12-30 05:12:50.9: Next periodic autofocus ASAP after 3600sec. 30-Dec-2021 06:12:25 UTC
2021-12-30 05:12:58.5: Dispatcher cycle time: 7.3 sec.
2021-12-30 05:12:58.5: Acquire data for Observation TU_Cas...
2021-12-30 05:12:58.6:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan TU_Cas)
2021-12-30 05:12:58.6: Send Observation TU_Cas to ACP Sequencer
2021-12-30 05:13:23.6: ** ACP script "AcquireScheduler" failed:
2021-12-30 05:13:23.6: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 1064 column 9.

2021-12-30 05:13:23.6: ACP ERROR: Run failed due to script error (see ACP run log)
2021-12-30 05:13:23.6: ACP data acquisition failed for Observation TU_Cas.
2021-12-30 05:13:23.6:   (Observation TU_Cas interrupted by acquisition process failure.)
2021-12-30 05:13:24.6: **EXCEPTION IN SCHEDULER:
2021-12-30 05:13:24.6: The telescope is not connected.
2021-12-30 05:13:25.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()
2021-12-30 05:13:25.0: No operator intervention command tool specified
2021-12-30 05:13:25.8: Run statistics:
2021-12-30 05:13:25.8:   Observations:   200
2021-12-30 05:13:25.8:   Considered:     4
2021-12-30 05:13:25.8:   Completed:      0
2021-12-30 05:13:25.8:   Skipped:        0
2021-12-30 05:13:25.8:   Never Eligible: 196
2021-12-30 05:13:25.8:   Failed:         4
2021-12-30 05:13:25.8: Shutter-Open efficiency: 30.94%
2021-12-30 05:13:25.8: Overall Efficiency: 99.72%
2021-12-30 05:13:25.8: Release ACP sequencer
Log closed at Thu, Dec 30 2021 05:13:27 UTC (actual time)