Log opened at Wed, Dec 01 2021 02:23:34 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-01 02:23:34.7: REAL TIME internal clock initialized at 01-Dec-2021 02:23:34 UTC 2021-12-01 02:23:34.7: Dispatcher started at 01-Dec-2021 02:23:34 UTC 2021-12-01 02:23:35.0: Loaded Constraint plugin AirMass 2021-12-01 02:23:35.0: Loaded Constraint plugin AirmassRange 2021-12-01 02:23:35.2: Loaded Constraint plugin Horizon 2021-12-01 02:23:35.4: Loaded Constraint plugin HourAngle 2021-12-01 02:23:35.4: Loaded Constraint plugin MoonAvoid 2021-12-01 02:23:35.5: Loaded Constraint plugin MoonDown 2021-12-01 02:23:35.5: Loaded Constraint plugin SkyCondition 2021-12-01 02:23:35.6: Loaded Constraint plugin TimeRange 2021-12-01 02:23:36.6: 4 plans are now pending. 2021-12-01 02:23:37.0: Attach ACP sequencer 2021-12-01 02:23:44.6: ==== Dispatcher is running in NORMAL (NIGHT) mode ==== 2021-12-01 02:23:44.7: Sequencer does sky flats 2021-12-01 02:23:44.7: Weather successfully connected. Wait for valid data... 2021-12-01 02:23:45.7: HTTP command listener failed to start: Access is denied 2021-12-01 02:23:45.7: If this is Windows 8-10, you will need to add permission (ACL) to listen on port 9123. 2021-12-01 02:23:45.7: See Scheduler Help > Configuring the Program > Noteworthy Configuration Options, at the bottom. 2021-12-01 02:23:50.2: ++ Weather Safe ++ 2021-12-01 02:23:52.7: ++ Observatory Startup ++ 2021-12-01 02:23:52.7: Weather safety is now disabled 2021-12-01 02:23:52.7: Start ACP Sequencer's StartupObs script 2021-12-01 02:24:27.4: Weather safety is re-enabled 2021-12-01 02:24:27.4: Weather safety is now disabled 2021-12-01 02:24:27.4: Open the observatory dome/roof 2021-12-01 02:25:28.2: Weather safety is re-enabled 2021-12-01 02:25:28.2: Observatory startup succeeded, and dome opened successfully 2021-12-01 02:25:28.2: ++ Auto Focus ++ 2021-12-01 02:25:28.2: Doing initial autofocus. 2021-12-01 02:25:28.2: Start special ACP AutoFocus script for scheduler 2021-12-01 02:31:05.0: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 03:30:50 UTC 2021-12-01 02:31:05.3: Plan MV_Lyr has Monitor Mode. Time to resubmit it. 2021-12-01 02:31:05.7: Dispatcher cycle time: 0.7 sec. 2021-12-01 02:31:05.7: Acquire data for Observation SA112... 2021-12-01 02:31:05.7: (belongs to Project Landolt, Plan SA112) 2021-12-01 02:31:05.7: Send Observation SA112 to ACP Sequencer 2021-12-01 02:38:48.8: Acquisition time: 463.0879956 sec. 2021-12-01 02:38:48.8: Data for Observation SA112 of Plan SA112 acquired successfully. 2021-12-01 02:38:48.9: Plan SA112 completed successfully. 2021-12-01 02:38:48.9: Image Efficiency: 41.0% 2021-12-01 02:38:48.9: Cycle Efficiency: 99.8% 2021-12-01 02:38:49.2: Dispatcher cycle time: 0.2 sec. 2021-12-01 02:38:49.2: Acquire data for Observation SA114... 2021-12-01 02:38:49.2: (belongs to Project Landolt, Plan SA114) 2021-12-01 02:38:49.3: Send Observation SA114 to ACP Sequencer 2021-12-01 02:46:03.3: Acquisition time: 434.0725488 sec. 2021-12-01 02:46:03.3: Data for Observation SA114 of Plan SA114 acquired successfully. 2021-12-01 02:46:03.4: Plan SA114 completed successfully. 2021-12-01 02:46:03.4: Image Efficiency: 32.2% 2021-12-01 02:46:03.4: Cycle Efficiency: 99.9% 2021-12-01 02:46:03.7: Dispatcher cycle time: 0.2 sec. 2021-12-01 02:46:03.7: Acquire data for Observation MV_Lyr... 2021-12-01 02:46:03.7: (belongs to Project AAVSO_P233_DKS_AUTO_ver2623, Plan MV_Lyr) 2021-12-01 02:46:03.7: Send Observation MV_Lyr to ACP Sequencer 2021-12-01 02:54:41.2: ACP data acquisition failed for Observation MV_Lyr. 2021-12-01 02:54:41.2: (Observation MV_Lyr interrupted by acquisition process failure.) 2021-12-01 02:54:41.8: Dispatcher cycle time: 0.1 sec. 2021-12-01 02:54:41.8: Acquire data for Observation SA94... 2021-12-01 02:54:41.8: (belongs to Project Landolt, Plan SA94) 2021-12-01 02:54:41.8: Send Observation SA94 to ACP Sequencer 2021-12-01 03:04:24.7: Acquisition time: 582.9175099 sec. 2021-12-01 03:04:24.7: Data for Observation SA94 of Plan SA94 acquired successfully. 2021-12-01 03:04:24.8: Plan SA94 completed successfully. 2021-12-01 03:04:24.9: Image Efficiency: 32.6% 2021-12-01 03:04:24.9: Cycle Efficiency: 100.0% 2021-12-01 03:04:25.2: Dispatcher cycle time: 0.2 sec. 2021-12-01 03:04:25.2: Acquire data for Observation GM_Aur... 2021-12-01 03:04:25.2: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan GM_Aur) 2021-12-01 03:04:25.2: Send Observation GM_Aur to ACP Sequencer 2021-12-01 03:08:05.5: ACP data acquisition failed for Observation GM_Aur. 2021-12-01 03:08:05.5: (Observation GM_Aur interrupted by acquisition process failure.) 2021-12-01 03:08:05.7: Dispatcher cycle time: 0.1 sec. 2021-12-01 03:08:05.7: Acquire data for Observation V1204_Tau... 2021-12-01 03:08:05.7: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan V1204_Tau) 2021-12-01 03:08:05.8: Send Observation V1204_Tau to ACP Sequencer 2021-12-01 03:12:07.9: ACP data acquisition failed for Observation V1204_Tau. 2021-12-01 03:12:07.9: (Observation V1204_Tau interrupted by acquisition process failure.) 2021-12-01 03:12:33.7: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2021-12-01 03:19:36.9: ++ Observatory Startup ++ 2021-12-01 03:19:36.9: Weather safety is now disabled 2021-12-01 03:19:36.9: Start ACP Sequencer's StartupObs script 2021-12-01 03:20:07.3: Weather safety is re-enabled 2021-12-01 03:20:07.3: Observatory startup succeeded 2021-12-01 03:20:07.3: ++ Auto Focus ++ 2021-12-01 03:20:07.3: Doing initial autofocus. 2021-12-01 03:20:07.3: Start special ACP AutoFocus script for scheduler 2021-12-01 03:24:33.4: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 04:24:15 UTC 2021-12-01 03:24:33.7: Dispatcher cycle time: 0.3 sec. 2021-12-01 03:24:33.7: Acquire data for Observation GM_Aur... 2021-12-01 03:24:33.7: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan GM_Aur) 2021-12-01 03:24:33.8: Send Observation GM_Aur to ACP Sequencer 2021-12-01 03:28:57.0: ACP data acquisition failed for Observation GM_Aur. 2021-12-01 03:28:57.0: (Observation GM_Aur interrupted by acquisition process failure.) 2021-12-01 03:28:57.4: Dispatcher cycle time: 0.1 sec. 2021-12-01 03:28:57.4: Acquire data for Observation V1204_Tau... 2021-12-01 03:28:57.4: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan V1204_Tau) 2021-12-01 03:28:57.4: Send Observation V1204_Tau to ACP Sequencer 2021-12-01 03:34:31.6: ACP data acquisition failed for Observation V1204_Tau. 2021-12-01 03:34:31.6: (Observation V1204_Tau interrupted by acquisition process failure.) 2021-12-01 03:37:51.2: Dispatcher cycle time: 0.2 sec. 2021-12-01 03:37:51.2: Acquire data for Observation GM_Aur... 2021-12-01 03:37:51.2: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan GM_Aur) 2021-12-01 03:37:51.2: Send Observation GM_Aur to ACP Sequencer 2021-12-01 03:39:53.7: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2021-12-01 03:40:01.1: ** ACP script "AcquireScheduler" failed: 2021-12-01 03:40:01.1: **Script Error** Source: ACP Message: The script was aborted. Location: line 1885 column 5. 2021-12-01 03:40:01.1: RUN FAILED: Scheduler was stopped during run 2021-12-01 03:40:01.2: ACP data acquisition failed for Observation GM_Aur. 2021-12-01 03:40:01.2: (Observation GM_Aur interrupted by operator or system.) 2021-12-01 03:44:53.4: ++ Observatory Startup ++ 2021-12-01 03:44:53.4: Weather safety is now disabled 2021-12-01 03:44:53.4: Start ACP Sequencer's StartupObs script 2021-12-01 03:45:25.4: Weather safety is re-enabled 2021-12-01 03:45:25.8: Observatory startup succeeded 2021-12-01 03:45:25.8: ++ Auto Focus ++ 2021-12-01 03:45:25.8: Doing initial autofocus. 2021-12-01 03:45:25.8: Start special ACP AutoFocus script for scheduler 2021-12-01 03:50:28.5: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 04:50:14 UTC 2021-12-01 03:50:28.8: Dispatcher cycle time: 0.3 sec. 2021-12-01 03:50:28.8: Acquire data for Observation GM_Aur... 2021-12-01 03:50:28.8: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan GM_Aur) 2021-12-01 03:50:28.8: Send Observation GM_Aur to ACP Sequencer 2021-12-01 04:06:13.0: Acquisition time: 944.1599258 sec. 2021-12-01 04:06:13.0: Data for Observation GM_Aur of Plan GM_Aur acquired successfully. 2021-12-01 04:06:13.1: Plan GM_Aur completed successfully. 2021-12-01 04:06:13.1: Image Efficiency: 57.2% 2021-12-01 04:06:13.1: Cycle Efficiency: 100.0% 2021-12-01 04:06:13.5: Dispatcher cycle time: 0.2 sec. 2021-12-01 04:06:13.5: Acquire data for Observation V1204_Tau... 2021-12-01 04:06:13.5: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan V1204_Tau) 2021-12-01 04:06:13.5: Send Observation V1204_Tau to ACP Sequencer 2021-12-01 04:15:00.5: Acquisition time: 526.9234087 sec. 2021-12-01 04:15:00.5: Data for Observation V1204_Tau of Plan V1204_Tau acquired successfully. 2021-12-01 04:15:00.5: Plan V1204_Tau completed successfully. 2021-12-01 04:15:00.6: Image Efficiency: 34.1% 2021-12-01 04:15:00.6: Cycle Efficiency: 99.9% 2021-12-01 04:37:59.1: Dispatcher cycle time: 0.2 sec. 2021-12-01 04:37:59.1: Acquire data for Observation V0505_Ori... 2021-12-01 04:37:59.1: (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan V0505_Ori) 2021-12-01 04:37:59.1: Send Observation V0505_Ori to ACP Sequencer 2021-12-01 04:58:11.2: Acquisition time: 1212.1313979 sec. 2021-12-01 04:58:11.2: Data for Observation V0505_Ori of Plan V0505_Ori acquired successfully. 2021-12-01 04:58:11.3: Plan V0505_Ori completed successfully. 2021-12-01 04:58:11.3: Image Efficiency: 74.2% 2021-12-01 04:58:11.3: Cycle Efficiency: 100.0% 2021-12-01 04:58:11.4: ++ Auto Focus ++ 2021-12-01 04:58:11.4: Doing periodic autofocus. 2021-12-01 04:58:11.4: Start special ACP AutoFocus script for scheduler 2021-12-01 05:03:34.2: ** Autofocus failed (n=1), will retry ASAP after 0 min. 2021-12-01 05:03:39.5: ++ Auto Focus ++ 2021-12-01 05:03:39.5: Doing periodic autofocus. 2021-12-01 05:03:39.5: Start special ACP AutoFocus script for scheduler 2021-12-01 05:08:41.9: ** Autofocus failed (n=2), will retry ASAP after 5 min. 2021-12-01 05:13:46.1: ++ Auto Focus ++ 2021-12-01 05:13:46.1: Doing periodic autofocus. 2021-12-01 05:13:46.1: Start special ACP AutoFocus script for scheduler 2021-12-01 05:18:11.4: ** Autofocus failed (n=3), will retry ASAP after 10 min. 2021-12-01 05:28:15.3: ++ Auto Focus ++ 2021-12-01 05:28:15.3: Doing periodic autofocus. 2021-12-01 05:28:15.3: Start special ACP AutoFocus script for scheduler 2021-12-01 05:36:13.1: ** Autofocus failed (n=4), will retry ASAP after 15 min. 2021-12-01 05:51:15.5: ++ Auto Focus ++ 2021-12-01 05:51:15.6: Doing periodic autofocus. 2021-12-01 05:51:15.6: Start special ACP AutoFocus script for scheduler 2021-12-01 05:54:51.3: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 06:54:37 UTC 2021-12-01 06:54:39.5: ++ Auto Focus ++ 2021-12-01 06:54:39.5: Doing periodic autofocus. 2021-12-01 06:54:39.5: Start special ACP AutoFocus script for scheduler 2021-12-01 06:57:25.4: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 07:57:07 UTC 2021-12-01 07:57:12.0: ++ Auto Focus ++ 2021-12-01 07:57:12.0: Doing periodic autofocus. 2021-12-01 07:57:12.0: Start special ACP AutoFocus script for scheduler 2021-12-01 08:01:29.6: ** Autofocus failed (n=1), will retry ASAP after 0 min. 2021-12-01 08:01:34.7: ++ Auto Focus ++ 2021-12-01 08:01:34.8: Doing periodic autofocus. 2021-12-01 08:01:34.8: Start special ACP AutoFocus script for scheduler 2021-12-01 08:06:48.5: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 09:06:34 UTC 2021-12-01 09:06:38.4: ++ Auto Focus ++ 2021-12-01 09:06:38.5: Doing periodic autofocus. 2021-12-01 09:06:38.5: Start special ACP AutoFocus script for scheduler 2021-12-01 09:10:34.1: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 10:10:13 UTC 2021-12-01 10:10:14.4: ++ Auto Focus ++ 2021-12-01 10:10:14.4: Doing periodic autofocus. 2021-12-01 10:10:14.4: Start special ACP AutoFocus script for scheduler 2021-12-01 10:13:38.8: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 11:13:25 UTC 2021-12-01 11:13:26.3: ++ Auto Focus ++ 2021-12-01 11:13:26.3: Doing periodic autofocus. 2021-12-01 11:13:26.3: Start special ACP AutoFocus script for scheduler 2021-12-01 11:18:58.7: Next periodic autofocus ASAP after 3600sec. 01-Dec-2021 12:18:40 UTC 2021-12-01 11:57:42.3: Run statistics: 2021-12-01 11:57:42.3: Observations: 4 2021-12-01 11:57:42.3: Considered: 2 2021-12-01 11:57:42.3: Completed: 0 2021-12-01 11:57:42.3: Skipped: 0 2021-12-01 11:57:42.3: Never Eligible: 2 2021-12-01 11:57:42.3: Failed: 2 Log closed at Wed, Dec 01 2021 11:57:42 UTC (actual time)