Log opened at Thu, Oct 21 2021 04:10:10 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2021-10-21 04:10:11.4: ++ Observatory Startup ++
2021-10-21 04:10:11.4: Start ACP Sequencer's StartupObs script
2021-10-21 04:10:11.4: ACP.RunScript(StartupObs, ):
2021-10-21 04:10:11.4:   Script FindBrightStar is loaded, and
2021-10-21 04:10:11.4:   the console is idle.
2021-10-21 04:10:11.4:   In start loop, i = 0
2021-10-21 04:10:11.5:   Console is now idle
2021-10-21 04:10:22.0:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2021-10-21 04:10:22.1:   Script started successfully
2021-10-21 04:10:32.1: Sequencer is now active
2021-10-21 04:10:46.3: Sequencer is no longer active
2021-10-21 04:10:46.3: Open the observatory dome/roof
2021-10-21 04:11:47.1: Observatory startup succeeded, and dome opened successfully
2021-10-21 04:11:47.1: ++ Auto Focus ++
2021-10-21 04:11:47.1: Doing initial autofocus.
2021-10-21 04:11:47.1: Start special ACP AutoFocus script for scheduler
2021-10-21 04:11:47.1: ACP.RunScript(AutoFocusScheduler, ):
2021-10-21 04:11:47.1:   Script StartupObs is loaded, and
2021-10-21 04:11:47.1:   the console is idle.
2021-10-21 04:11:47.1:   In start loop, i = 0
2021-10-21 04:11:47.1:   Console is now idle
2021-10-21 04:11:47.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2021-10-21 04:11:47.3:   Script started successfully
2021-10-21 04:11:57.3: Sequencer is now active
2021-10-21 04:17:24.9: Sequencer is no longer active
2021-10-21 04:17:24.9: Next periodic autofocus ASAP after 3600sec. 21-Oct-2021 05:17:05 UTC
2021-10-21 04:17:24.9: SelectRunning: No running plans
2021-10-21 04:17:24.9: No pending plans that can be started now.
2021-10-21 04:17:24.9: Dispatcher cycle time: 0.1 sec.
2021-10-21 04:17:30.8: SelectRunning: No running plans
2021-10-21 04:17:30.8: No pending plans that can be started now.
2021-10-21 04:17:30.8: Dispatcher cycle time: 0.0 sec.
2021-10-21 04:17:36.2: SelectRunning: No running plans
2021-10-21 04:17:36.2: No pending plans that can be started now.
2021-10-21 04:17:36.2: Dispatcher cycle time: 0.0 sec.
2021-10-21 04:17:41.7: SelectRunning: No running plans
2021-10-21 04:17:41.7: No pending plans that can be started now.
2021-10-21 04:17:41.7: Dispatcher cycle time: 0.0 sec.
2021-10-21 04:17:47.2: SelectRunning: No running plans
2021-10-21 04:17:47.2: Efficiency: Only one pending plan test, it is the "best"
2021-10-21 04:17:47.2: Dispatcher cycle time: 0.1 sec.
2021-10-21 04:17:47.2: Acquire data for Observation test...
2021-10-21 04:17:47.2:   (belongs to Project Transit, Plan test)
2021-10-21 04:17:47.2: Doing 10 sets
2021-10-21 04:17:47.3: Send Observation test to ACP Sequencer
2021-10-21 04:17:47.3: ACP.RunScript(AcquireScheduler, ):
2021-10-21 04:17:47.3:   Script AutoFocusScheduler is loaded, and
2021-10-21 04:17:47.3:   the console is idle.
2021-10-21 04:17:47.3:   In start loop, i = 0
2021-10-21 04:17:47.3:   Console is now idle
2021-10-21 04:17:47.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2021-10-21 04:17:47.4:   Script started successfully
2021-10-21 04:17:57.4: Sequencer is now active
2021-10-21 04:26:12.3: Sequencer is no longer active
2021-10-21 04:26:12.3: Post-job status check done (stat=Completed)
2021-10-21 04:26:12.4: Send Observation test to ACP Sequencer
2021-10-21 04:26:12.4: ACP.RunScript(AcquireScheduler, ):
2021-10-21 04:26:12.4:   Script AcquireScheduler is loaded, and
2021-10-21 04:26:12.4:   the console is idle.
2021-10-21 04:26:12.4:   In start loop, i = 0
2021-10-21 04:26:12.4:   Console is now idle
2021-10-21 04:26:12.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2021-10-21 04:26:12.4:   Script started successfully
2021-10-21 04:26:22.4: Sequencer is now active
2021-10-21 04:27:49.6: Sequencer is no longer active
2021-10-21 04:27:49.6: ** ACP script "AcquireScheduler" failed:
2021-10-21 04:27:49.6: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1440 column 13.
**Script Error**
ACP script error 0x80073B01 from ThinScript.ActiveScript:
The resource loader cache doesn't have loaded MUI entry..

2021-10-21 04:27:49.6: ACP OPERATOR ABORT: The operator killed the run
2021-10-21 04:27:49.6: ACP data acquisition failed for Observation test.
2021-10-21 04:27:49.6:   (Observation test interrupted by acquisition process failure.)
2021-10-21 04:27:49.8: SelectRunning: No running plans
2021-10-21 04:27:49.8: No pending plans that can be started now.
2021-10-21 04:27:49.8: Dispatcher cycle time: 0.0 sec.
2021-10-21 04:27:55.0: SelectRunning: No running plans
2021-10-21 04:27:55.0: No pending plans that can be started now.
2021-10-21 04:27:55.0: Dispatcher cycle time: 0.0 sec.
2021-10-21 04:28:00.2: SelectRunning: No running plans
2021-10-21 04:28:00.2: No pending plans that can be started now.
2021-10-21 04:28:00.2: Dispatcher cycle time: 0.0 sec.
Log level changed from Debug to Normal
2021-10-21 04:28:26.2: Dispatcher cycle time: 0.1 sec.
2021-10-21 04:28:26.2: Acquire data for Observation TIC 470127886.01...
2021-10-21 04:28:26.2:   (belongs to Project Transit, Plan TIC 470127886.01)
2021-10-21 04:28:26.2: Doing 10 sets
2021-10-21 04:28:26.2: Send Observation TIC 470127886.01 to ACP Sequencer
2021-10-21 06:52:24.7: Send Observation TIC 470127886.01 to ACP Sequencer
2021-10-21 09:14:59.0: ***Running Plan TIC 470127886.01 (best efforts) completed early:
2021-10-21 09:14:59.0:    Obs TIC 470127886.01 would not meet strict constraints
2021-10-21 09:14:59.0: Acquisition time: 17192.7138956 sec.
2021-10-21 09:14:59.0: Data for Observation TIC 470127886.01 of Plan TIC 470127886.01 acquired successfully.
2021-10-21 09:14:59.0: Plan TIC 470127886.01 completed successfully.
2021-10-21 09:14:59.0: Image Efficiency: 84.0%
2021-10-21 09:14:59.0: Cycle Efficiency: 100.0%
2021-10-21 09:14:59.2: ++ Auto Focus ++
2021-10-21 09:14:59.3: Doing periodic autofocus.
2021-10-21 09:14:59.3: Start special ACP AutoFocus script for scheduler
2021-10-21 09:19:51.0: Next periodic autofocus ASAP after 3600sec. 21-Oct-2021 10:19:32 UTC
2021-10-21 10:19:34.4: ++ Auto Focus ++
2021-10-21 10:19:34.4: Doing periodic autofocus.
2021-10-21 10:19:34.4: Start special ACP AutoFocus script for scheduler
2021-10-21 10:23:45.2: Next periodic autofocus ASAP after 3600sec. 21-Oct-2021 11:23:25 UTC
2021-10-21 11:23:27.9: ++ Auto Focus ++
2021-10-21 11:23:27.9: Doing periodic autofocus.
2021-10-21 11:23:27.9: Start special ACP AutoFocus script for scheduler
2021-10-21 11:27:32.2: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2021-10-21 11:27:37.6: ++ Auto Focus ++
2021-10-21 11:27:37.6: Doing periodic autofocus.
2021-10-21 11:27:37.6: Start special ACP AutoFocus script for scheduler
2021-10-21 11:31:03.4: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2021-10-21 11:36:04.9: ++ Auto Focus ++
2021-10-21 11:36:04.9: Doing periodic autofocus.
2021-10-21 11:36:04.9: Start special ACP AutoFocus script for scheduler
2021-10-21 11:39:52.5: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2021-10-21 11:49:57.9: ++ Auto Focus ++
2021-10-21 11:49:57.9: Doing periodic autofocus.
2021-10-21 11:49:57.9: Start special ACP AutoFocus script for scheduler
2021-10-21 11:53:22.6: ** Autofocus failed (n=4), will retry ASAP after 15 min.
2021-10-21 12:08:26.2: ++ Auto Focus ++
2021-10-21 12:08:26.2: Doing periodic autofocus.
2021-10-21 12:08:26.2: Start special ACP AutoFocus script for scheduler
2021-10-21 12:12:30.2: Next periodic autofocus ASAP after 3600sec. 21-Oct-2021 13:12:15 UTC
2021-10-21 12:33:53.7: ++ Dawn Flats ++
2021-10-21 12:33:53.7: Start ACP AutoFlat script for Dawn flats
2021-10-21 13:22:42.9: ** Script interrupted by weather safety event.
2021-10-21 16:24:41.7: ** ACP script "ACP-Weather" failed:
2021-10-21 16:24:41.7: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 33 column 9.
**Script Error**
ACP script error 0x80073B01 from ThinScript.ActiveScript:
The resource loader cache doesn't have loaded MUI entry..

2021-10-21 16:24:41.8: -- Weather Unsafe --
2021-10-21 16:24:48.1: -- Observatory Shutdown --
2021-10-21 16:24:48.2: Start ACP Sequencer's ShutdownObs script
2021-10-21 16:32:35.2: **EXCEPTION IN SCHEDULER:
2021-10-21 16:32:35.2: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
2021-10-21 16:32:35.8: Traceback:
   at ACP.UtilClass.get_Scheduler()
   at DC3.Scheduler.ACPSequencer.set_DispatcherStatus(StatusValue value)
   at DC3.Scheduler.Engine.set_Status(StatusValue value)
   at DC3.Scheduler.Engine.Run()
2021-10-21 16:32:35.8: DOME/ROOF FAILURE: In addition, attempting to close your dome/roof this error occurred:
2021-10-21 16:32:35.8: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
2021-10-21 16:32:36.2: No operator intervention command tool specified