Log opened at Tue, Dec 07 2021 01:23:21 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-07 01:23:21.6: ++ Observatory Startup ++
2021-12-07 01:23:21.6: Weather safety is now disabled
2021-12-07 01:23:21.6: Start ACP Sequencer's StartupObs script
2021-12-07 01:23:55.7: Weather safety is re-enabled
2021-12-07 01:23:55.8: Weather safety is now disabled
2021-12-07 01:23:55.8: Open the observatory dome/roof
2021-12-07 01:25:11.8: Weather safety is re-enabled
2021-12-07 01:25:11.8: Observatory startup succeeded, and dome opened successfully
2021-12-07 01:25:11.8: ++ Auto Focus ++
2021-12-07 01:25:11.8: Doing initial autofocus.
2021-12-07 01:25:11.8: Start special ACP AutoFocus script for scheduler
2021-12-07 01:29:27.8: ** ACP script "AutoFocusScheduler" failed:
2021-12-07 01:29:27.8: **Script Error (Tracking has been stopped)**
Source:   MaxIm.CCDCamera
Message:  **Exposure failed after being successfully started
Location: line 81 column 2.

2021-12-07 01:29:27.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-12-07 01:29:28.3: Plan MV_Lyr has Monitor Mode. Time to resubmit it.
2021-12-07 01:29:28.3: Plan GM_Aur has Monitor Mode. Time to resubmit it.
2021-12-07 01:29:28.3: Plan V1204_Tau has Monitor Mode. Time to resubmit it.
2021-12-07 01:29:28.6: Dispatcher cycle time: 0.7 sec.
2021-12-07 01:29:28.6: Acquire data for Observation MV_Lyr...
2021-12-07 01:29:28.6:   (belongs to Project AAVSO_P233_DKS_AUTO_ver2623, Plan MV_Lyr)
2021-12-07 01:29:28.6: ++ Auto Focus ++
2021-12-07 01:29:28.6: Doing periodic autofocus.
2021-12-07 01:29:28.6: Start special ACP AutoFocus script for scheduler
2021-12-07 01:32:56.1: ** ACP script "AutoFocusScheduler" failed:
2021-12-07 01:32:56.1: **Script Error (Tracking has been stopped)**
Source:   MaxIm.CCDCamera
Message:  **Exposure failed after being successfully started
Location: line 81 column 2.

2021-12-07 01:32:56.1: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-12-07 01:32:56.1: Send Observation MV_Lyr to ACP Sequencer
2021-12-07 02:10:35.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-07 02:10:44.3: ** ACP script "AcquireScheduler" failed:
2021-12-07 02:10:44.3: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1885 column 5.

2021-12-07 02:10:44.4: RUN FAILED: Scheduler was stopped during run
2021-12-07 02:10:44.5: ACP data acquisition failed for Observation MV_Lyr.
2021-12-07 02:10:44.5:   (Observation MV_Lyr interrupted by operator or system.)
2021-12-07 02:12:04.6: ++ Observatory Startup ++
2021-12-07 02:12:04.6: Weather safety is now disabled
2021-12-07 02:12:04.6: Start ACP Sequencer's StartupObs script
2021-12-07 02:12:35.8: Weather safety is re-enabled
2021-12-07 02:12:35.8: Observatory startup succeeded
2021-12-07 02:12:35.8: ++ Auto Focus ++
2021-12-07 02:12:35.8: Doing initial autofocus.
2021-12-07 02:12:35.8: Start special ACP AutoFocus script for scheduler
2021-12-07 02:18:01.7: Next periodic autofocus ASAP after 3600sec. 07-Dec-2021 03:17:47 UTC
2021-12-07 02:18:02.1: Dispatcher cycle time: 0.3 sec.
2021-12-07 02:18:02.1: Acquire data for Observation GM_Aur...
2021-12-07 02:18:02.1:   (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan GM_Aur)
2021-12-07 02:18:02.1: Send Observation GM_Aur to ACP Sequencer
2021-12-07 02:29:22.3: ACP data acquisition failed for Observation GM_Aur.
2021-12-07 02:29:22.3:   (Observation GM_Aur interrupted by acquisition process failure.)
2021-12-07 02:29:22.8: Dispatcher cycle time: 0.2 sec.
2021-12-07 02:29:22.8: Acquire data for Observation V1204_Tau...
2021-12-07 02:29:22.8:   (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan V1204_Tau)
2021-12-07 02:29:22.8: Send Observation V1204_Tau to ACP Sequencer
2021-12-07 02:37:22.6: Acquisition time: 479.7686011 sec.
2021-12-07 02:37:22.6: Data for Observation V1204_Tau of Plan V1204_Tau acquired successfully.
2021-12-07 02:37:22.6: Plan V1204_Tau completed successfully.
2021-12-07 02:37:22.6: Image Efficiency: 37.5%
2021-12-07 02:37:22.6: Cycle Efficiency: 99.9%
2021-12-07 03:17:50.7: ++ Auto Focus ++
2021-12-07 03:17:50.8: Doing periodic autofocus.
2021-12-07 03:17:50.8: Start special ACP AutoFocus script for scheduler
2021-12-07 04:04:16.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-07 04:05:20.2: ** ACP script "AutoFocusScheduler" failed:
2021-12-07 04:05:20.2: **Script Error (Tracking has been stopped)**
Source:   ACP Observatory Control Software
Message:  
Location: line 81 column 2.

2021-12-07 04:05:20.2: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-12-07 04:06:49.4: -- Weather Unsafe --
2021-12-07 04:08:52.2: Weather successfully connected. Wait for valid data...
2021-12-07 04:27:51.9: ++ Weather Safe ++
2021-12-07 04:27:52.9: ++ Observatory Startup ++
2021-12-07 04:27:52.9: Weather safety is now disabled
2021-12-07 04:27:52.9: Start ACP Sequencer's StartupObs script
2021-12-07 04:28:30.0: Weather safety is re-enabled
2021-12-07 04:28:30.0: Observatory startup succeeded
2021-12-07 04:28:30.0: ++ Auto Focus ++
2021-12-07 04:28:30.1: Doing initial autofocus.
2021-12-07 04:28:30.1: Start special ACP AutoFocus script for scheduler
2021-12-07 04:28:45.1: ** ACP script "AutoFocusScheduler" failed:
2021-12-07 04:28:45.1: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2021-12-07 04:28:45.1: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-12-07 04:28:50.4: ++ Auto Focus ++
2021-12-07 04:28:50.4: Doing periodic autofocus.
2021-12-07 04:28:50.4: Start special ACP AutoFocus script for scheduler
2021-12-07 04:29:05.5: ** ACP script "AutoFocusScheduler" failed:
2021-12-07 04:29:05.5: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2021-12-07 04:29:05.5: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-12-07 04:30:09.2: Weather safety is now disabled
2021-12-07 04:30:09.2: Open the observatory dome/roof
2021-12-07 04:31:25.0: Weather safety is re-enabled
2021-12-07 04:31:25.0: ++ Auto Focus ++
2021-12-07 04:31:25.0: Doing initial autofocus.
2021-12-07 04:31:25.0: Start special ACP AutoFocus script for scheduler
2021-12-07 04:37:00.1: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2021-12-07 04:40:16.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-07 04:45:19.8: ++ Observatory Startup ++
2021-12-07 04:45:19.8: Weather safety is now disabled
2021-12-07 04:45:19.8: Start ACP Sequencer's StartupObs script
2021-12-07 04:45:49.7: Weather safety is re-enabled
2021-12-07 04:45:49.7: Observatory startup succeeded
2021-12-07 04:45:49.7: ++ Auto Focus ++
2021-12-07 04:45:49.7: Doing initial autofocus.
2021-12-07 04:45:49.7: Start special ACP AutoFocus script for scheduler
2021-12-07 04:49:58.2: Next periodic autofocus ASAP after 3600sec. 07-Dec-2021 05:49:43 UTC
2021-12-07 04:58:56.2: Weather safety is now disabled
2021-12-07 04:58:56.5: **Dome closing when asked to open. Assuming weather closure.
2021-12-07 04:58:56.8: Weather safety is now disabled
2021-12-07 04:58:57.3: **Dome already closing when asked to close. Assuming weather closure.
2021-12-07 04:59:27.9: Weather safety is re-enabled
2021-12-07 05:00:06.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-07 05:01:28.5:  but the weather remained safe for 2 minutes, something really went wrong.
2021-12-07 05:01:28.5: Weather safety is re-enabled
2021-12-07 05:01:28.6: **EXCEPTION IN SCHEDULER:
2021-12-07 05:01:28.6: **Dome failed to open for reason other than hardware weather closure
2021-12-07 05:01:28.7: Traceback:
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2021-12-07 05:01:28.7: Weather safety is now disabled
2021-12-07 05:01:28.7: Weather safety is re-enabled
2021-12-07 05:01:28.9: No operator intervention command tool specified
2021-12-07 05:01:29.2: Run statistics:
2021-12-07 05:01:29.2:   Observations:   6
2021-12-07 05:01:29.2:   Considered:     3
2021-12-07 05:01:29.2:   Completed:      0
2021-12-07 05:01:29.2:   Skipped:        0
2021-12-07 05:01:29.2:   Never Eligible: 3
2021-12-07 05:01:29.2:   Failed:         3
2021-12-07 05:01:29.2: Shutter-Open efficiency: 2.71%
2021-12-07 05:01:29.2: Overall Efficiency: 51.53%
2021-12-07 05:01:29.2: Release ACP sequencer
Log closed at Tue, Dec 07 2021 05:01:31 UTC (actual time)