Log opened at Mon, Dec 06 2021 01:34:17 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-06 01:34:17.5: REAL TIME internal clock initialized at 06-Dec-2021 01:34:17 UTC
2021-12-06 01:34:17.5: Dispatcher started at 06-Dec-2021 01:34:17 UTC
2021-12-06 01:34:17.6: Loaded Constraint plugin AirMass
2021-12-06 01:34:17.7: Loaded Constraint plugin AirmassRange
2021-12-06 01:34:17.7: Loaded Constraint plugin Horizon
2021-12-06 01:34:17.7: Loaded Constraint plugin HourAngle
2021-12-06 01:34:17.7: Loaded Constraint plugin MoonAvoid
2021-12-06 01:34:17.7: Loaded Constraint plugin MoonDown
2021-12-06 01:34:17.7: Loaded Constraint plugin SkyCondition
2021-12-06 01:34:17.7: Loaded Constraint plugin TimeRange
2021-12-06 01:34:18.6: 5 plans are now pending.
2021-12-06 01:34:18.7: Attach ACP sequencer
2021-12-06 01:34:24.8: There is no controllable dome or roof.
2021-12-06 01:34:24.9: ++ Weather Safe ++
2021-12-06 01:34:25.5: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2021-12-06 01:34:25.5: Sequencer does sky flats
2021-12-06 01:34:27.0: HTTP command listener failed to start: Access is denied
2021-12-06 01:34:27.0:   If this is Windows 8-10, you will need to add permission (ACL) to listen on port 9123.
2021-12-06 01:34:27.0:   See Scheduler Help > Configuring the Program > Noteworthy Configuration Options, at the bottom.
2021-12-06 01:34:34.1: ++ Observatory Startup ++
2021-12-06 01:34:34.1: Weather safety is now disabled
2021-12-06 01:34:34.1: Start ACP Sequencer's StartupObs script
2021-12-06 01:35:07.3: Weather safety is re-enabled
2021-12-06 01:35:07.3: Observatory startup succeeded
2021-12-06 01:35:07.3: ++ Auto Focus ++
2021-12-06 01:35:07.3: Doing initial autofocus.
2021-12-06 01:35:07.3: Start special ACP AutoFocus script for scheduler
2021-12-06 01:35:21.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-06 01:35:22.5: ** ACP script "AutoFocusScheduler" failed:
2021-12-06 01:35:22.5: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2021-12-06 01:35:22.5: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-12-06 01:35:34.0: ++ Observatory Startup ++
2021-12-06 01:35:34.0: Weather safety is now disabled
2021-12-06 01:35:34.0: Start ACP Sequencer's StartupObs script
2021-12-06 01:36:01.0: Weather safety is re-enabled
2021-12-06 01:36:01.0: Weather safety is now disabled
2021-12-06 01:36:01.0: Open the observatory dome/roof
2021-12-06 01:37:02.1: Weather safety is re-enabled
2021-12-06 01:37:02.1: Observatory startup succeeded, and dome opened successfully
2021-12-06 01:37:02.1: ++ Auto Focus ++
2021-12-06 01:37:02.1: Doing initial autofocus.
2021-12-06 01:37:02.1: Start special ACP AutoFocus script for scheduler
2021-12-06 01:43:21.1: Next periodic autofocus ASAP after 3600sec. 06-Dec-2021 02:42:55 UTC
2021-12-06 01:43:21.5: Dispatcher cycle time: 0.4 sec.
2021-12-06 01:43:21.5: Acquire data for Observation MV_Lyr...
2021-12-06 01:43:21.5:   (belongs to Project AAVSO_P233_DKS_AUTO_ver2623, Plan MV_Lyr)
2021-12-06 01:43:21.6: Send Observation MV_Lyr to ACP Sequencer
2021-12-06 02:18:25.9: Acquisition time: 2104.3064678 sec.
2021-12-06 02:18:25.9: Data for Observation MV_Lyr of Plan MV_Lyr acquired successfully.
2021-12-06 02:18:25.9: Plan MV_Lyr completed successfully.
2021-12-06 02:18:25.9: Image Efficiency: 85.5%
2021-12-06 02:18:25.9: Cycle Efficiency: 100.0%
2021-12-06 02:18:26.1: Dispatcher cycle time: 0.1 sec.
2021-12-06 02:18:26.1: Acquire data for Observation GM_Aur...
2021-12-06 02:18:26.1:   (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan GM_Aur)
2021-12-06 02:18:26.1: Send Observation GM_Aur to ACP Sequencer
2021-12-06 02:35:02.5: Acquisition time: 996.3641541 sec.
2021-12-06 02:35:02.5: Data for Observation GM_Aur of Plan GM_Aur acquired successfully.
2021-12-06 02:35:02.5: Plan GM_Aur completed successfully.
2021-12-06 02:35:02.6: Image Efficiency: 54.2%
2021-12-06 02:35:02.6: Cycle Efficiency: 100.0%
2021-12-06 02:35:02.9: Dispatcher cycle time: 0.2 sec.
2021-12-06 02:35:02.9: Acquire data for Observation V1204_Tau...
2021-12-06 02:35:02.9:   (belongs to Project AAVSO_P226_WFM_AUTO_ver2663, Plan V1204_Tau)
2021-12-06 02:35:02.9: Send Observation V1204_Tau to ACP Sequencer
2021-12-06 02:49:10.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2021-12-06 02:51:32.8: ** ACP script "AcquireScheduler" failed:
2021-12-06 02:51:32.8: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 1160 column 17.

2021-12-06 02:51:32.8: RUN FAILED: Scheduler was stopped during run
2021-12-06 02:51:32.9: ACP data acquisition failed for Observation V1204_Tau.
2021-12-06 02:51:32.9:   (Observation V1204_Tau interrupted by operator or system.)
2021-12-06 02:53:16.7: -- Weather Unsafe --
2021-12-06 03:19:02.4: ++ Weather Safe ++
2021-12-06 03:24:56.4: ++ Observatory Startup ++
2021-12-06 03:24:56.6: Weather safety is now disabled
2021-12-06 03:24:56.7: Start ACP Sequencer's StartupObs script
2021-12-06 03:24:57.5: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:25:57.6: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:26:57.9: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:27:58.1: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:28:58.4: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:29:58.6: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:30:58.8: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:31:59.0: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:32:59.2: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:33:59.3: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:34:59.7: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:35:59.9: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:37:00.2: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:38:00.5: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:39:00.8: WARNING: Request sent while Weather Safety Script is running
2021-12-06 03:40:00.8: ERROR: Failed to start ACP script due to ACP being busy or locked
2021-12-06 03:40:00.8: Weather safety is re-enabled
2021-12-06 03:40:00.9: **EXCEPTION IN SCHEDULER:
2021-12-06 03:40:00.9: Observatory startup failed, cannot continue.
2021-12-06 03:40:01.1: Traceback:
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2021-12-06 03:40:01.9: Weather safety is now disabled
2021-12-06 03:40:02.2: Weather safety is re-enabled
2021-12-06 03:43:17.6: No operator intervention command tool specified
2021-12-06 03:43:18.1: Run statistics:
2021-12-06 03:43:18.1:   Observations:   5
2021-12-06 03:43:18.1:   Considered:     2
2021-12-06 03:43:18.1:   Completed:      0
2021-12-06 03:43:18.1:   Skipped:        0
2021-12-06 03:43:18.1:   Never Eligible: 3
2021-12-06 03:43:18.1:   Failed:         2
2021-12-06 03:43:18.1: Shutter-Open efficiency: 57.19%
2021-12-06 03:43:18.1: Overall Efficiency: 99.90%
2021-12-06 03:43:18.1: Release ACP sequencer
Log closed at Mon, Dec 06 2021 03:43:20 UTC (actual time)