Log opened at Sat, Oct 05 2019 10:47:47 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2019-10-05 10:47:47.9: ++ Observatory Startup ++
2019-10-05 10:47:47.9: Startup attempted, but no StartupObs script found
2019-10-05 10:47:47.9: Open the observatory dome/roof
2019-10-05 10:48:48.6: Observatory startup succeeded, and dome opened successfully
2019-10-05 10:48:48.7: ++ Auto Focus ++
2019-10-05 10:48:48.7: Doing initial autofocus.
2019-10-05 10:48:48.7: Start special ACP AutoFocus script for scheduler
2019-10-05 10:48:48.8: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 10:48:48.8:   No script is loaded. ACP is definitely idle
2019-10-05 10:48:48.8:   In start loop, i = 0
2019-10-05 10:48:48.8:   Console is now idle
2019-10-05 10:48:48.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 10:48:48.8:   Script started successfully
2019-10-05 10:48:58.8: Sequencer is now active
2019-10-05 10:49:03.9: Sequencer is no longer active
2019-10-05 10:49:03.9: ** ACP script "AutoFocusScheduler" failed:
2019-10-05 10:49:03.9: **Script Error (Tracking has been stopped)**
Source:   ACP
Message:  The camera is not connected.
Location: line 42 column 5.

2019-10-05 10:49:03.9: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-05 10:49:04.1: SelectRunning: No running plans
2019-10-05 10:49:04.1: Efficiency: Only one pending plan KH15D, it is the "best"
2019-10-05 10:49:04.2: Dispatcher cycle time: 0.2 sec.
2019-10-05 10:49:04.2: Acquire data for Observation KH15D...
2019-10-05 10:49:04.2:   (belongs to Project KH15D, Plan KH15D)
2019-10-05 10:49:04.2: ++ Auto Focus ++
2019-10-05 10:49:04.2: Doing periodic autofocus.
2019-10-05 10:49:04.2: Start special ACP AutoFocus script for scheduler
2019-10-05 10:49:04.2: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 10:49:04.2:   Script AutoFocusScheduler is loaded, and
2019-10-05 10:49:04.2:   the console is idle.
2019-10-05 10:49:04.2:   In start loop, i = 0
2019-10-05 10:49:04.2:   Console is now idle
2019-10-05 10:49:04.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 10:49:04.2:   Script started successfully
2019-10-05 10:49:14.2: Sequencer is now active
2019-10-05 10:49:19.2: Sequencer is no longer active
2019-10-05 10:49:19.2: ** ACP script "AutoFocusScheduler" failed:
2019-10-05 10:49:19.2: **Script Error (Tracking has been stopped)**
Source:   ACP
Message:  The camera is not connected.
Location: line 42 column 5.

2019-10-05 10:49:19.2: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-10-05 10:49:19.3: Send Observation KH15D to ACP Sequencer
2019-10-05 10:49:19.3: ACP.RunScript(AcquireScheduler, ):
2019-10-05 10:49:19.3:   Script AutoFocusScheduler is loaded, and
2019-10-05 10:49:19.3:   the console is idle.
2019-10-05 10:49:19.3:   In start loop, i = 0
2019-10-05 10:49:19.3:   Console is now idle
2019-10-05 10:49:19.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-05 10:49:19.3:   Script started successfully
2019-10-05 10:49:29.4: Sequencer is now active
2019-10-05 10:49:34.4: Sequencer is no longer active
2019-10-05 10:49:34.4: ** ACP script "AcquireScheduler" failed:
2019-10-05 10:49:34.4: **Script Error (Tracking has been stopped)**
Source:   ACP
Message:  The camera is not connected.
Location: line 946 column 5.

2019-10-05 10:49:34.4: ACP ERROR: Run failed due to script error (see ACP run log)
2019-10-05 10:49:34.4: ACP data acquisition failed for Observation KH15D.
2019-10-05 10:49:34.4:   (Observation KH15D interrupted by acquisition process failure.)
2019-10-05 10:49:34.6: SelectRunning: No running plans
2019-10-05 10:49:34.6: No pending plans that can be started now.
2019-10-05 10:49:34.6: Dispatcher cycle time: 0.0 sec.
2019-10-05 10:50:05.2: SelectRunning: No running plans
2019-10-05 10:50:05.2: No pending plans that can be started now.
2019-10-05 10:50:05.2: Dispatcher cycle time: 0.0 sec.
2019-10-05 10:50:14.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-10-05 10:50:17.5: ++ Observatory Startup ++
2019-10-05 10:50:17.5: Startup attempted, but no StartupObs script found
2019-10-05 10:50:17.5: Observatory startup succeeded
2019-10-05 10:50:17.5: ++ Auto Focus ++
2019-10-05 10:50:17.5: Doing initial autofocus.
2019-10-05 10:50:17.5: Start special ACP AutoFocus script for scheduler
2019-10-05 10:50:17.5: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 10:50:17.5:   Script AcquireScheduler is loaded, and
2019-10-05 10:50:17.5:   the console is idle.
2019-10-05 10:50:17.5:   In start loop, i = 0
2019-10-05 10:50:17.5:   Console is now idle
2019-10-05 10:50:17.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 10:50:17.6:   Script started successfully
2019-10-05 10:50:27.6: Sequencer is now active
2019-10-05 10:54:17.1: Sequencer is no longer active
2019-10-05 10:54:17.1: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-05 10:54:17.4: SelectRunning: No running plans
2019-10-05 10:54:17.5: No pending plans that can be started now.
2019-10-05 10:54:17.5: Dispatcher cycle time: 0.4 sec.
2019-10-05 10:54:48.0: ++ Auto Focus ++
2019-10-05 10:54:48.1: Doing periodic autofocus.
2019-10-05 10:54:48.1: Start special ACP AutoFocus script for scheduler
2019-10-05 10:54:48.1: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 10:54:48.1:   Script AutoFocusScheduler is loaded, and
2019-10-05 10:54:48.1:   the console is idle.
2019-10-05 10:54:48.1:   In start loop, i = 0
2019-10-05 10:54:48.1:   Console is now idle
2019-10-05 10:54:48.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 10:54:48.1:   Script started successfully
2019-10-05 10:54:58.1: Sequencer is now active
2019-10-05 10:57:07.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-10-05 10:57:12.2: Sequencer is no longer active
2019-10-05 10:57:12.2: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-10-05 10:57:21.1: Requested shutdown of scheduler
2019-10-05 10:57:21.1: Run statistics:
2019-10-05 10:57:21.1:   Observations:   1
2019-10-05 10:57:21.1:   Considered:     1
2019-10-05 10:57:21.1:   Completed:      0
2019-10-05 10:57:21.1:   Skipped:        0
2019-10-05 10:57:21.1:   Never Eligible: 0
2019-10-05 10:57:21.1:   Failed:         1
2019-10-05 10:57:21.1: Shutter-Open efficiency: 0.00%
2019-10-05 10:57:21.1: Overall Efficiency: 14.88%
2019-10-05 10:57:21.1: Release ACP sequencer
Log closed at Sat, Oct 05 2019 10:57:22 UTC (actual time)