Log opened at Sat, Oct 05 2019 10:59:30 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2019-10-05 10:59:30.8: ++ Observatory Startup ++
2019-10-05 10:59:30.8: Startup attempted, but no StartupObs script found
2019-10-05 10:59:30.8: Observatory startup succeeded
2019-10-05 10:59:30.8: ++ Auto Focus ++
2019-10-05 10:59:30.8: Doing initial autofocus.
2019-10-05 10:59:30.8: Start special ACP AutoFocus script for scheduler
2019-10-05 10:59:30.8: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 10:59:30.8:   No script is loaded. ACP is definitely idle
2019-10-05 10:59:30.8:   In start loop, i = 0
2019-10-05 10:59:30.8:   Console is now idle
2019-10-05 10:59:30.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 10:59:30.9:   Script started successfully
2019-10-05 10:59:40.9: Sequencer is now active
2019-10-05 10:59:45.9: Sequencer is no longer active
2019-10-05 10:59:45.9: ** ACP script "AutoFocusScheduler" failed:
2019-10-05 10:59:45.9: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2019-10-05 10:59:45.9: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-05 10:59:45.9: SelectRunning: No running plans
2019-10-05 10:59:45.9: No pending plans that can be started now.
2019-10-05 10:59:45.9: Dispatcher cycle time: 0.0 sec.
2019-10-05 10:59:48.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-10-05 11:00:17.5: Efficiency weights are set to:
2019-10-05 11:00:17.5:     Priority:          W[0]=0.00
2019-10-05 11:00:17.5:     Transit Altitude:  W[1]=0.00
2019-10-05 11:00:17.5:     Highest Altitude:  W[6]=0.00
2019-10-05 11:00:17.5:     Lateness:          W[5]=0.00
2019-10-05 11:00:17.5:     Slew Distance:     W[2]=0.00
2019-10-05 11:00:17.5:     Retry Count:       W[3]=0.00
2019-10-05 11:00:17.5:     Meridian Crossing: W[4]=0.00
2019-10-05 11:00:17.5:     Obs Conditions:    W[7]=0.00
2019-10-05 11:00:17.5:     Proj Completion:   W[8]=0.00
2019-10-05 11:00:17.5:     Rising Plan Delay: disabled
2019-10-05 11:00:32.7: ++ Observatory Startup ++
2019-10-05 11:00:32.7: Startup attempted, but no StartupObs script found
2019-10-05 11:00:32.8: Observatory startup succeeded
2019-10-05 11:00:32.8: ++ Auto Focus ++
2019-10-05 11:00:32.8: Doing initial autofocus.
2019-10-05 11:00:32.8: Start special ACP AutoFocus script for scheduler
2019-10-05 11:00:32.8: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 11:00:32.8:   Script AutoFocusScheduler is loaded, and
2019-10-05 11:00:32.8:   the console is idle.
2019-10-05 11:00:32.8:   In start loop, i = 0
2019-10-05 11:00:32.8:   Console is now idle
2019-10-05 11:00:32.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 11:00:32.8:   Script started successfully
2019-10-05 11:00:42.8: Sequencer is now active
2019-10-05 11:00:43.0: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-10-05 11:00:47.8: Sequencer is no longer active
2019-10-05 11:00:47.8: ** ACP script "AutoFocusScheduler" failed:
2019-10-05 11:00:47.8: **Script Error**
Source:   ACP
Message:  The telescope is not connected.
Location: line 42 column 5.

2019-10-05 11:00:47.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-05 11:00:55.8: ++ Observatory Startup ++
2019-10-05 11:00:55.8: Startup attempted, but no StartupObs script found
2019-10-05 11:00:55.8: Observatory startup succeeded
2019-10-05 11:00:55.8: ++ Auto Focus ++
2019-10-05 11:00:55.8: Doing initial autofocus.
2019-10-05 11:00:55.8: Start special ACP AutoFocus script for scheduler
2019-10-05 11:00:55.8: ACP.RunScript(AutoFocusScheduler, ):
2019-10-05 11:00:55.8:   Script AutoFocusScheduler is loaded, and
2019-10-05 11:00:55.8:   the console is idle.
2019-10-05 11:00:55.8:   In start loop, i = 0
2019-10-05 11:00:55.8:   Console is now idle
2019-10-05 11:00:55.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-05 11:00:55.8:   Script started successfully
2019-10-05 11:01:05.8: Sequencer is now active
2019-10-05 11:04:43.9: Sequencer is no longer active
2019-10-05 11:04:43.9: Next periodic autofocus ASAP after 3600sec. 05-Oct-2019 12:04:24 UTC
2019-10-05 11:04:43.9: SelectRunning: No running plans
2019-10-05 11:04:43.9: No pending plans that can be started now.
2019-10-05 11:04:43.9: Dispatcher cycle time: 0.0 sec.
2019-10-05 11:05:14.5: SelectRunning: No running plans
2019-10-05 11:05:14.5: No pending plans that can be started now.
2019-10-05 11:05:14.5: Dispatcher cycle time: 0.0 sec.
2019-10-05 11:05:45.1: SelectRunning: No running plans
2019-10-05 11:05:45.1: No pending plans that can be started now.
2019-10-05 11:05:45.1: Dispatcher cycle time: 0.0 sec.
2019-10-05 11:06:15.7: SelectRunning: No running plans
2019-10-05 11:06:15.7: No pending plans that can be started now.
2019-10-05 11:06:15.7: Dispatcher cycle time: 0.0 sec.
2019-10-05 11:06:46.4: SelectRunning: No running plans
2019-10-05 11:06:46.4: Efficiency: Only one pending plan KH15D, it is the "best"
2019-10-05 11:06:46.4: Dispatcher cycle time: 0.2 sec.
2019-10-05 11:06:46.4: Acquire data for Observation KH15D...
2019-10-05 11:06:46.4:   (belongs to Project KH15D, Plan KH15D)
2019-10-05 11:06:46.5: Send Observation KH15D to ACP Sequencer
2019-10-05 11:06:46.5: ACP.RunScript(AcquireScheduler, ):
2019-10-05 11:06:46.5:   Script AutoFocusScheduler is loaded, and
2019-10-05 11:06:46.5:   the console is idle.
2019-10-05 11:06:46.5:   In start loop, i = 0
2019-10-05 11:06:46.5:   Console is now idle
2019-10-05 11:06:46.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-05 11:06:46.5:   Script started successfully
2019-10-05 11:06:56.5: Sequencer is now active
2019-10-05 11:11:45.2: Sequencer is no longer active
2019-10-05 11:11:45.2: Post-job status check done (stat=Failed)
2019-10-05 11:11:45.2: ACP data acquisition failed for Observation KH15D.
2019-10-05 11:11:45.2:   (Observation KH15D interrupted by acquisition process failure.)
2019-10-05 11:11:45.3: SelectRunning: No running plans
2019-10-05 11:11:45.3: No pending plans that can be started now.
2019-10-05 11:11:45.3: Dispatcher cycle time: 0.0 sec.
2019-10-05 11:11:53.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-10-05 12:41:52.0: -- Weather Unsafe --
2019-10-06 01:33:27.5: ++ Weather Safe ++
2019-10-06 01:59:06.6: Run statistics:
2019-10-06 01:59:06.6:   Observations:   1
2019-10-06 01:59:06.6:   Considered:     1
2019-10-06 01:59:06.6:   Completed:      0
2019-10-06 01:59:06.6:   Skipped:        0
2019-10-06 01:59:06.6:   Never Eligible: 0
2019-10-06 01:59:06.6:   Failed:         1
Log closed at Sun, Oct 06 2019 01:59:06 UTC (actual time)