Log opened at Sat, Apr 02 2022 02:08:51 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-04-02 02:08:51.6: REAL TIME internal clock initialized at 02-Apr-2022 02:08:51 UTC
2022-04-02 02:08:51.7: Dispatcher started at 02-Apr-2022 02:08:51 UTC
2022-04-02 02:08:52.0: Loaded Constraint plugin AirMass
2022-04-02 02:08:52.1: Loaded Constraint plugin AirmassRange
2022-04-02 02:08:52.3: Loaded Constraint plugin Horizon
2022-04-02 02:08:52.4: Loaded Constraint plugin HourAngle
2022-04-02 02:08:52.5: Loaded Constraint plugin MoonAvoid
2022-04-02 02:08:52.5: Loaded Constraint plugin MoonDown
2022-04-02 02:08:52.6: Loaded Constraint plugin SkyCondition
2022-04-02 02:08:52.6: Loaded Constraint plugin TimeRange
2022-04-02 02:08:53.8: 181 plans are now pending.
2022-04-02 02:08:54.0: Attach ACP sequencer
2022-04-02 02:09:01.5: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2022-04-02 02:09:01.6: Sequencer does sky flats
2022-04-02 02:09:01.6: Weather successfully connected. Wait for valid data...
2022-04-02 02:09:07.4: ++ Weather Safe ++
2022-04-02 02:09:07.4: HTTP command listener failed to start: Access is denied
2022-04-02 02:09:07.4:   If this is Windows 8-10, you will need to add permission (ACL) to listen on port 9123.
2022-04-02 02:09:07.4:   See Scheduler Help > Configuring the Program > Noteworthy Configuration Options, at the bottom.
2022-04-02 02:09:20.7: ++ Observatory Startup ++
2022-04-02 02:09:21.0: Weather safety is now disabled
2022-04-02 02:09:21.0: Start ACP Sequencer's StartupObs script
2022-04-02 02:10:15.3: Weather safety is re-enabled
2022-04-02 02:10:15.3: Weather safety is now disabled
2022-04-02 02:10:15.3: Open the observatory dome/roof
2022-04-02 02:11:16.2: Weather safety is re-enabled
2022-04-02 02:11:16.2: Observatory startup succeeded, and dome opened successfully
2022-04-02 02:11:16.2: ++ Auto Focus ++
2022-04-02 02:11:16.2: Doing initial autofocus.
2022-04-02 02:11:16.2: Start special ACP AutoFocus script for scheduler
2022-04-02 02:16:51.6: Next periodic autofocus ASAP after 3600sec. 02-Apr-2022 03:16:36 UTC
2022-04-02 02:16:58.9: Plan NGC_5634 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan IR_Com has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan SU_Lyn has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan IX_Dra has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan AG_Dra has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan BX_Pup has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan GZ_Cnc has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan HD168418 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan HQ_Mon has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan HS1340+1524 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan IR_Com has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan J072816 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan L1022_IRS has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan MN84 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan NSV_11154 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan NSV_11749 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan OI_158 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan OJ287 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan PNV_17291350 has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan PV_Cep has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan SU_Lyn has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan U_Leo has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan U_Sco has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan V884_Her has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan X_Boo has Monitor Mode. Time to resubmit it.
2022-04-02 02:16:59.0: Plan X_Ser has Monitor Mode. Time to resubmit it.
2022-04-02 02:17:00.6: Plan Melotte_111 deferred, too early
2022-04-02 02:17:01.1: Plan NGC_7790 deferred, too early
2022-04-02 02:17:02.6: Plan V493_Gem couldn't be run before latest time, failed.
2022-04-02 02:17:04.0: Dispatcher cycle time: 12.4 sec.
2022-04-02 02:17:04.0: Acquire data for Observation BX_Pup...
2022-04-02 02:17:04.1:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan BX_Pup)
2022-04-02 02:17:04.1: Send Observation BX_Pup to ACP Sequencer
2022-04-02 02:29:32.7: Acquisition time: 748.619843 sec.
2022-04-02 02:29:32.7: Data for Observation BX_Pup of Plan BX_Pup acquired successfully.
2022-04-02 02:29:32.8: Plan BX_Pup completed successfully.
2022-04-02 02:29:32.8: Image Efficiency: 70.9%
2022-04-02 02:29:32.8: Cycle Efficiency: 98.4%
2022-04-02 02:29:35.7: Dispatcher cycle time: 2.8 sec.
2022-04-02 02:29:35.7: Acquire data for Observation J072816...
2022-04-02 02:29:35.7:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan J072816)
2022-04-02 02:29:35.8: Send Observation J072816 to ACP Sequencer
2022-04-02 02:56:18.1: Acquisition time: 1602.2976759 sec.
2022-04-02 02:56:18.1: Data for Observation J072816 of Plan J072816 acquired successfully.
2022-04-02 02:56:18.1: Plan J072816 completed successfully.
2022-04-02 02:56:18.1: Image Efficiency: 71.0%
2022-04-02 02:56:18.1: Cycle Efficiency: 99.8%
2022-04-02 02:56:21.1: Dispatcher cycle time: 2.8 sec.
2022-04-02 02:56:21.1: Acquire data for Observation OJ287...
2022-04-02 02:56:21.1:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan OJ287)
2022-04-02 02:56:21.1: Send Observation OJ287 to ACP Sequencer
2022-04-02 03:17:16.7: Acquisition time: 1255.6111825 sec.
2022-04-02 03:17:16.7: Data for Observation OJ287 of Plan OJ287 acquired successfully.
2022-04-02 03:17:16.8: Plan OJ287 completed successfully.
2022-04-02 03:17:16.8: Image Efficiency: 62.0%
2022-04-02 03:17:16.8: Cycle Efficiency: 99.8%
2022-04-02 03:17:16.9: ++ Auto Focus ++
2022-04-02 03:17:17.0: Doing periodic autofocus.
2022-04-02 03:17:17.0: Start special ACP AutoFocus script for scheduler
2022-04-02 03:23:03.4: Next periodic autofocus ASAP after 3600sec. 02-Apr-2022 04:22:48 UTC
2022-04-02 03:23:09.6: Dispatcher cycle time: 6.2 sec.
2022-04-02 03:23:09.6: Acquire data for Observation GZ_Cnc...
2022-04-02 03:23:09.6:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan GZ_Cnc)
2022-04-02 03:23:09.6: Send Observation GZ_Cnc to ACP Sequencer
2022-04-02 03:23:24.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2022-04-02 03:23:31.7: ** ACP script "AcquireScheduler" failed:
2022-04-02 03:23:31.7: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1064 column 9.

2022-04-02 03:23:31.7: RUN FAILED: Scheduler was stopped during run
2022-04-02 03:23:31.7: ACP data acquisition failed for Observation GZ_Cnc.
2022-04-02 03:23:31.7:   (Observation GZ_Cnc interrupted by operator or system.)
2022-04-02 03:39:53.2: ++ Observatory Startup ++
2022-04-02 03:39:53.2: Weather safety is now disabled
2022-04-02 03:39:53.2: Start ACP Sequencer's StartupObs script
2022-04-02 03:40:47.2: Weather safety is re-enabled
2022-04-02 03:40:47.2: Observatory startup succeeded
2022-04-02 03:40:47.2: ++ Auto Focus ++
2022-04-02 03:40:47.2: Doing initial autofocus.
2022-04-02 03:40:47.2: Start special ACP AutoFocus script for scheduler
2022-04-02 03:43:19.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2022-04-02 03:46:04.6: Next periodic autofocus ASAP after 3600sec. 02-Apr-2022 04:45:46 UTC
2022-04-02 04:00:40.2: ++ Observatory Startup ++
2022-04-02 04:00:40.2: Weather safety is now disabled
2022-04-02 04:00:40.2: Start ACP Sequencer's StartupObs script
2022-04-02 04:01:40.4: Weather safety is re-enabled
2022-04-02 04:01:40.4: Observatory startup succeeded
2022-04-02 04:01:40.4: ++ Auto Focus ++
2022-04-02 04:01:40.4: Doing initial autofocus.
2022-04-02 04:01:40.4: Start special ACP AutoFocus script for scheduler
2022-04-02 04:05:56.3: Next periodic autofocus ASAP after 3600sec. 02-Apr-2022 05:05:42 UTC
2022-04-02 04:06:07.5: Plan Melotte_111 deferred, too early
2022-04-02 04:06:08.0: Plan NGC_7790 deferred, too early
2022-04-02 04:06:10.4: Dispatcher cycle time: 14.1 sec.
2022-04-02 04:06:10.4: Acquire data for Observation U_Leo...
2022-04-02 04:06:10.4:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan U_Leo)
2022-04-02 04:06:10.4: Send Observation U_Leo to ACP Sequencer
2022-04-02 04:18:38.0: Acquisition time: 747.6114742 sec.
2022-04-02 04:18:38.0: Data for Observation U_Leo of Plan U_Leo acquired successfully.
2022-04-02 04:18:38.0: Plan U_Leo completed successfully.
2022-04-02 04:18:38.1: Image Efficiency: 63.0%
2022-04-02 04:18:38.1: Cycle Efficiency: 98.1%
2022-04-02 04:18:39.5: Dispatcher cycle time: 1.2 sec.
2022-04-02 04:18:39.5: Acquire data for Observation M67...
2022-04-02 04:18:39.5:   (belongs to Project AAVSO_Transform_Std_Fields_AUTO_ver2774, Plan M67)
2022-04-02 04:18:39.5: Send Observation M67 to ACP Sequencer
2022-04-02 04:44:09.3: Acquisition time: 1529.7607294 sec.
2022-04-02 04:44:09.3: Data for Observation M67 of Plan M67 acquired successfully.
2022-04-02 04:44:09.3: Plan M67 completed successfully.
2022-04-02 04:44:09.4: Image Efficiency: 60.1%
2022-04-02 04:44:09.4: Cycle Efficiency: 99.9%
2022-04-02 04:44:10.9: Dispatcher cycle time: 1.4 sec.
2022-04-02 04:44:10.9: Acquire data for Observation IR_Com...
2022-04-02 04:44:10.9:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan IR_Com)
2022-04-02 04:44:11.0: Send Observation IR_Com to ACP Sequencer
2022-04-02 05:00:27.3: Acquisition time: 976.2767462 sec.
2022-04-02 05:00:27.3: Data for Observation IR_Com of Plan IR_Com acquired successfully.
2022-04-02 05:00:27.3: Plan IR_Com completed successfully.
2022-04-02 05:00:27.3: Image Efficiency: 73.6%
2022-04-02 05:00:27.3: Cycle Efficiency: 99.8%
2022-04-02 05:00:28.2: Dispatcher cycle time: 0.8 sec.
2022-04-02 05:00:28.2: Acquire data for Observation SU_Lyn...
2022-04-02 05:00:28.2:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan SU_Lyn)
2022-04-02 05:00:28.3: Send Observation SU_Lyn to ACP Sequencer
2022-04-02 05:09:52.2: Acquisition time: 563.917328 sec.
2022-04-02 05:09:52.2: Data for Observation SU_Lyn of Plan SU_Lyn acquired successfully.
2022-04-02 05:09:52.2: Plan SU_Lyn completed successfully.
2022-04-02 05:09:52.2: Image Efficiency: 38.4%
2022-04-02 05:09:52.2: Cycle Efficiency: 99.8%
2022-04-02 05:09:52.3: ++ Auto Focus ++
2022-04-02 05:09:52.4: Doing periodic autofocus.
2022-04-02 05:09:52.4: Start special ACP AutoFocus script for scheduler
2022-04-02 05:14:30.6: Next periodic autofocus ASAP after 3600sec. 02-Apr-2022 06:14:11 UTC
2022-04-02 05:14:31.3: Dispatcher cycle time: 0.7 sec.
2022-04-02 05:14:31.3: Acquire data for Observation HS1340+1524...
2022-04-02 05:14:31.3:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan HS1340+1524)
2022-04-02 05:14:31.3: Send Observation HS1340+1524 to ACP Sequencer
2022-04-02 05:25:31.7: Acquisition time: 660.3544694 sec.
2022-04-02 05:25:31.7: Data for Observation HS1340+1524 of Plan HS1340+1524 acquired successfully.
2022-04-02 05:25:31.7: Plan HS1340+1524 completed successfully.
2022-04-02 05:25:31.7: Image Efficiency: 54.5%
2022-04-02 05:25:31.7: Cycle Efficiency: 99.9%
2022-04-02 05:25:32.6: Dispatcher cycle time: 0.7 sec.
2022-04-02 05:25:32.6: Acquire data for Observation AG_Dra...
2022-04-02 05:25:32.6:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan AG_Dra)
2022-04-02 05:25:32.6: Send Observation AG_Dra to ACP Sequencer
2022-04-02 05:32:38.5: Acquisition time: 425.9320433 sec.
2022-04-02 05:32:38.5: Data for Observation AG_Dra of Plan AG_Dra acquired successfully.
2022-04-02 05:32:38.6: Plan AG_Dra completed successfully.
2022-04-02 05:32:38.6: Image Efficiency: 42.2%
2022-04-02 05:32:38.6: Cycle Efficiency: 99.8%
2022-04-02 05:32:39.5: Dispatcher cycle time: 0.7 sec.
2022-04-02 05:32:39.5: Acquire data for Observation IR_Com...
2022-04-02 05:32:39.5:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan IR_Com)
2022-04-02 05:32:39.5: Send Observation IR_Com to ACP Sequencer
2022-04-02 05:49:30.4: Acquisition time: 1010.8557763 sec.
2022-04-02 05:49:30.4: Data for Observation IR_Com of Plan IR_Com acquired successfully.
2022-04-02 05:49:30.4: Plan IR_Com completed successfully.
2022-04-02 05:49:30.4: Image Efficiency: 71.2%
2022-04-02 05:49:30.4: Cycle Efficiency: 99.9%
2022-04-02 05:49:31.2: Dispatcher cycle time: 0.6 sec.
2022-04-02 05:49:31.2: Acquire data for Observation X_Boo...
2022-04-02 05:49:31.2:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan X_Boo)
2022-04-02 05:49:31.2: Send Observation X_Boo to ACP Sequencer
2022-04-02 05:56:40.2: Acquisition time: 428.9713909 sec.
2022-04-02 05:56:40.2: Data for Observation X_Boo of Plan X_Boo acquired successfully.
2022-04-02 05:56:40.2: Plan X_Boo completed successfully.
2022-04-02 05:56:40.2: Image Efficiency: 10.7%
2022-04-02 05:56:40.2: Cycle Efficiency: 99.8%
2022-04-02 05:56:41.1: Dispatcher cycle time: 0.7 sec.
2022-04-02 05:56:41.1: Acquire data for Observation OI_158...
2022-04-02 05:56:41.1:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan OI_158)
2022-04-02 05:56:41.1: Send Observation OI_158 to ACP Sequencer
2022-04-02 06:12:57.9: Acquisition time: 976.7591322 sec.
2022-04-02 06:12:57.9: Data for Observation OI_158 of Plan OI_158 acquired successfully.
2022-04-02 06:12:57.9: Plan OI_158 completed successfully.
2022-04-02 06:12:57.9: Image Efficiency: 73.7%
2022-04-02 06:12:57.9: Cycle Efficiency: 99.9%
2022-04-02 06:12:58.7: Dispatcher cycle time: 0.7 sec.
2022-04-02 06:12:58.7: Acquire data for Observation SU_Lyn...
2022-04-02 06:12:58.7:   (belongs to Project AAVSO_MISC_HQA_AUTO_ver2706, Plan SU_Lyn)
2022-04-02 06:12:58.7: Send Observation SU_Lyn to ACP Sequencer
2022-04-02 06:22:14.0: Acquisition time: 555.223439 sec.
2022-04-02 06:22:14.0: Data for Observation SU_Lyn of Plan SU_Lyn acquired successfully.
2022-04-02 06:22:14.0: Plan SU_Lyn completed successfully.
2022-04-02 06:22:14.0: Image Efficiency: 48.6%
2022-04-02 06:22:14.0: Cycle Efficiency: 99.9%
2022-04-02 06:22:14.2: ++ Auto Focus ++
2022-04-02 06:22:14.2: Doing periodic autofocus.
2022-04-02 06:22:14.2: Start special ACP AutoFocus script for scheduler
2022-04-02 06:27:45.5: Next periodic autofocus ASAP after 3600sec. 02-Apr-2022 07:27:31 UTC
2022-04-02 06:27:45.9: Dispatcher cycle time: 0.4 sec.
2022-04-02 06:27:45.9: Acquire data for Observation NGC_5634...
2022-04-02 06:27:45.9:   (belongs to Project AAVSO_Pgc_Welch_AUTO_ver2411, Plan NGC_5634)
2022-04-02 06:27:46.0: Send Observation NGC_5634 to ACP Sequencer
2022-04-02 06:59:58.7: Acquisition time: 1932.7899877 sec.
2022-04-02 06:59:58.7: Data for Observation NGC_5634 of Plan NGC_5634 acquired successfully.
2022-04-02 06:59:58.8: Plan NGC_5634 completed successfully.
2022-04-02 06:59:58.8: Image Efficiency: 75.5%
2022-04-02 06:59:58.8: Cycle Efficiency: 100.0%
2022-04-02 06:59:59.5: Dispatcher cycle time: 0.5 sec.
2022-04-02 06:59:59.5: Acquire data for Observation Pal_5...
2022-04-02 06:59:59.5:   (belongs to Project AAVSO_Pgc_Welch_AUTO_ver2411, Plan Pal_5)
2022-04-02 06:59:59.5: Send Observation Pal_5 to ACP Sequencer
2022-04-02 07:04:23.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2022-04-02 07:04:30.9: ** ACP script "AcquireScheduler" failed:
2022-04-02 07:04:30.9: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1487 column 13.

2022-04-02 07:04:30.9: RUN FAILED: Scheduler was stopped during run
2022-04-02 07:04:30.9: ACP data acquisition failed for Observation Pal_5.
2022-04-02 07:04:30.9:   (Observation Pal_5 interrupted by operator or system.)
2022-04-02 17:07:37.4: -- Weather Unsafe --
2022-04-02 17:28:40.6: ++ Weather Safe ++
2022-04-02 20:13:53.2: -- Weather Unsafe --
2022-04-02 20:34:55.4: ++ Weather Safe ++
2022-04-05 23:13:05.0: -- Weather Unsafe --
2022-04-05 23:33:16.9: ++ Weather Safe ++
2022-04-05 23:38:14.3: -- Weather Unsafe --
2022-04-05 23:58:28.6: ++ Weather Safe ++
2022-04-06 01:48:29.4: Run statistics:
2022-04-06 01:48:29.4:   Observations:   269
2022-04-06 01:48:29.4:   Considered:     15
2022-04-06 01:48:29.4:   Completed:      0
2022-04-06 01:48:29.4:   Skipped:        0
2022-04-06 01:48:29.4:   Never Eligible: 254
2022-04-06 01:48:29.4:   Failed:         15
Log closed at Wed, Apr 06 2022 01:48:29 UTC (actual time)