Log opened at Mon, Oct 14 2019 19:02:03 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2019-10-15 01:20:40.9: ++ Weather Safe ++
2019-10-15 01:20:41.9: ++ Observatory Startup ++
2019-10-15 01:20:41.9: Startup attempted, but no StartupObs script found
2019-10-15 01:20:41.9: Observatory startup succeeded
2019-10-15 01:29:21.6: ++ Auto Focus ++
2019-10-15 01:29:21.7: Doing initial autofocus.
2019-10-15 01:29:21.7: Start special ACP AutoFocus script for scheduler
2019-10-15 01:29:21.7: ACP.RunScript(AutoFocusScheduler, ):
2019-10-15 01:29:21.7:   Script ShutdownObs is loaded, and
2019-10-15 01:29:21.7:   the console is idle.
2019-10-15 01:29:21.7:   In start loop, i = 0
2019-10-15 01:29:21.7:   Console is now idle
2019-10-15 01:29:21.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-15 01:29:21.7:   Script started successfully
2019-10-15 01:29:31.7: Sequencer is now active
2019-10-15 01:29:36.8: Sequencer is no longer active
2019-10-15 01:29:36.8: ** ACP script "AutoFocusScheduler" failed:
2019-10-15 01:29:36.8: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2019-10-15 01:29:36.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-15 01:29:36.8: Plan KH15D has Monitor Mode. Time to resubmit it.
2019-10-15 01:29:36.8: Plan KH15D-B has Monitor Mode. Time to resubmit it.
2019-10-15 01:29:36.8: SelectRunning: No running plans
2019-10-15 01:29:36.8: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2019-10-15 01:29:36.8:     AirMass estimates ready after 15-Oct-2019 08:11:53 UTC
2019-10-15 01:29:36.8: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2019-10-15 01:29:36.8:     AirMass estimates ready after 15-Oct-2019 08:11:53 UTC
2019-10-15 01:29:36.9: Efficiency: Selecting from 2 pending plans:
2019-10-15 01:29:36.9:   Selected Plan SA109 (TotEff=0.00)
2019-10-15 01:29:37.0: Dispatcher cycle time: 0.2 sec.
2019-10-15 01:29:37.0: Acquire data for Observation SA109...
2019-10-15 01:29:37.0:   (belongs to Project Landolt fields, Plan SA109)
2019-10-15 01:29:37.0: Doing 3 sets
2019-10-15 01:29:37.0: ++ Auto Focus ++
2019-10-15 01:29:37.0: Doing periodic autofocus.
2019-10-15 01:29:37.0: Start special ACP AutoFocus script for scheduler
2019-10-15 01:29:37.0: ACP.RunScript(AutoFocusScheduler, ):
2019-10-15 01:29:37.0:   Script AutoFocusScheduler is loaded, and
2019-10-15 01:29:37.0:   the console is idle.
2019-10-15 01:29:37.0:   In start loop, i = 0
2019-10-15 01:29:37.0:   Console is now idle
2019-10-15 01:29:37.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-15 01:29:37.0:   Script started successfully
2019-10-15 01:29:47.0: Sequencer is now active
2019-10-15 01:29:52.1: Sequencer is no longer active
2019-10-15 01:29:52.1: ** ACP script "AutoFocusScheduler" failed:
2019-10-15 01:29:52.1: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2019-10-15 01:29:52.1: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-10-15 01:29:52.2: **EXCEPTION IN SCHEDULER:
2019-10-15 01:29:52.2: The telescope is not connected.
2019-10-15 01:29:52.4: Traceback:
   at ACP.TelescopeClass.get_SlewSettleTime()
   at DC3.Scheduler.ACPSequencer.AcquireData(Observation Obs)
   at DC3.Scheduler.Engine.DoSchedulePass()
   at DC3.Scheduler.Engine.Run()
2019-10-15 01:29:52.5: No operator intervention command tool specified
2019-10-15 01:29:52.5: Run statistics:
2019-10-15 01:29:52.5:   Observations:   4
2019-10-15 01:29:52.5:   Considered:     0
2019-10-15 01:29:52.5:   Completed:      0
2019-10-15 01:29:52.5:   Skipped:        0
2019-10-15 01:29:52.5:   Never Eligible: 4
2019-10-15 01:29:52.5:   Failed:         0
2019-10-15 01:29:52.5: Shutter-Open efficiency: 29.87%
2019-10-15 01:29:52.5: Overall Efficiency: 36.09%
2019-10-15 01:29:52.5: Release ACP sequencer
Log closed at Tue, Oct 15 2019 01:29:54 UTC (actual time)