Log opened at Sat, Oct 12 2019 04:20:35 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2019-10-12 04:20:35.9: ++ Observatory Startup ++
2019-10-12 04:20:35.9: Startup attempted, but no StartupObs script found
2019-10-12 04:20:35.9: Observatory startup succeeded
2019-10-12 04:20:35.9: ++ Auto Focus ++
2019-10-12 04:20:36.0: Doing initial autofocus.
2019-10-12 04:20:36.0: Start special ACP AutoFocus script for scheduler
2019-10-12 04:20:36.0: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 04:20:36.0:   Script AcquireScheduler is loaded, and
2019-10-12 04:20:36.0:   the console is idle.
2019-10-12 04:20:36.0:   In start loop, i = 0
2019-10-12 04:20:36.0:   Console is now idle
2019-10-12 04:20:36.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 04:20:36.0:   Script started successfully
2019-10-12 04:20:46.0: Sequencer is now active
2019-10-12 04:22:50.9: Sequencer is no longer active
2019-10-12 04:22:50.9: ** ACP script "AutoFocusScheduler" failed:
2019-10-12 04:22:50.9: **Script Error (Tracking has been stopped)**
Source:   FocusMax
Message:  Out of memory
Location: line 81 column 2.

2019-10-12 04:22:50.9: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-12 04:22:51.1: SelectRunning: No running plans
2019-10-12 04:22:51.1: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2019-10-12 04:22:51.1:     AirMass estimates ready after 12-Oct-2019 08:23:41 UTC
2019-10-12 04:22:51.1: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2019-10-12 04:22:51.1:     AirMass estimates ready after 12-Oct-2019 08:23:41 UTC
2019-10-12 04:22:51.1: Obs SA109 in Plan SA109 was vetoed by AirMass constraint.
2019-10-12 04:22:51.1:     AirMass estimates ready after 12-Oct-2019 19:54:00 UTC
2019-10-12 04:22:51.1: Efficiency: Only one pending plan Test, it is the "best"
2019-10-12 04:22:51.2: Dispatcher cycle time: 0.2 sec.
2019-10-12 04:22:51.2: Acquire data for Observation Test...
2019-10-12 04:22:51.2:   (belongs to Project KH15D, Plan Test)
2019-10-12 04:22:51.2: ++ Auto Focus ++
2019-10-12 04:22:51.2: Doing periodic autofocus.
2019-10-12 04:22:51.2: Start special ACP AutoFocus script for scheduler
2019-10-12 04:22:51.2: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 04:22:51.2:   Script AutoFocusScheduler is loaded, and
2019-10-12 04:22:51.2:   the console is idle.
2019-10-12 04:22:51.2:   In start loop, i = 0
2019-10-12 04:22:51.2:   Console is now idle
2019-10-12 04:22:51.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 04:22:51.6:   Script started successfully
2019-10-12 04:23:01.6: Sequencer is now active
2019-10-12 04:24:48.1: Sequencer is no longer active
2019-10-12 04:24:48.2: ** ACP script "AutoFocusScheduler" failed:
2019-10-12 04:24:48.2: **Script Error (Tracking has been stopped)**
Source:   FocusMax
Message:  Unexpected error
Location: line 81 column 2.

2019-10-12 04:24:48.2: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-10-12 04:24:48.2: Send Observation Test to ACP Sequencer
2019-10-12 04:24:48.2: ACP.RunScript(AcquireScheduler, ):
2019-10-12 04:24:48.2:   Script AutoFocusScheduler is loaded, and
2019-10-12 04:24:48.2:   the console is idle.
2019-10-12 04:24:48.2:   In start loop, i = 0
2019-10-12 04:24:48.2:   Console is now idle
2019-10-12 04:24:48.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-12 04:24:48.2:   Script started successfully
2019-10-12 04:24:58.2: Sequencer is now active
2019-10-12 04:37:34.6: Sequencer is no longer active
2019-10-12 04:37:34.6: Post-job status check done (stat=Completed)
2019-10-12 04:37:34.6: Acquisition time: 766.4299947 sec.
2019-10-12 04:37:34.6: Data for Observation Test of Plan Test acquired successfully.
2019-10-12 04:37:34.7: Plan Test completed successfully.
2019-10-12 04:37:34.7: Image Efficiency: 78.3%
2019-10-12 04:37:34.7: Cycle Efficiency: 100.0%
2019-10-12 04:37:35.0: ++ Auto Focus ++
2019-10-12 04:37:35.0: Doing periodic autofocus.
2019-10-12 04:37:35.0: Start special ACP AutoFocus script for scheduler
2019-10-12 04:37:35.0: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 04:37:35.0:   Script AcquireScheduler is loaded, and
2019-10-12 04:37:35.0:   the console is idle.
2019-10-12 04:37:35.0:   In start loop, i = 0
2019-10-12 04:37:35.0:   Console is now idle
2019-10-12 04:37:35.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 04:37:35.0:   Script started successfully
2019-10-12 04:37:45.1: Sequencer is now active
2019-10-12 04:39:52.5: Sequencer is no longer active
2019-10-12 04:39:52.5: ** ACP script "AutoFocusScheduler" failed:
2019-10-12 04:39:52.5: **Script Error (Tracking has been stopped)**
Source:   FocusMax
Message:  Out of memory
Location: line 81 column 2.

2019-10-12 04:39:52.5: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2019-10-12 04:39:52.5: SelectRunning: No running plans
2019-10-12 04:39:52.5: No pending plans that can be started now.
2019-10-12 04:39:52.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:40:23.1: SelectRunning: No running plans
2019-10-12 04:40:23.1: No pending plans that can be started now.
2019-10-12 04:40:23.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:40:53.6: **EXCEPTION IN SCHEDULER:
2019-10-12 04:40:53.6: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
2019-10-12 04:40:53.6: 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()
2019-10-12 04:40:53.6: DOME/ROOF FAILURE: In addition, attempting to close your dome/roof this error occurred:
2019-10-12 04:40:53.6: The RPC server is unavailable. (Exception from HRESULT: 0x800706BA)
2019-10-12 04:40:53.6: No operator intervention command tool specified