Log opened at Mon, Oct 21 2019 07:05:02 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2019-10-21 07:39:12.1: ++ Weather Safe ++
2019-10-21 07:39:13.2: ++ Observatory Startup ++
2019-10-21 07:39:13.2: Startup attempted, but no StartupObs script found
2019-10-21 07:39:13.2: Open the observatory dome/roof
2019-10-21 07:40:29.0: Observatory startup succeeded, and dome opened successfully
2019-10-21 07:40:29.0: ++ Auto Focus ++
2019-10-21 07:40:29.0: Doing initial autofocus.
2019-10-21 07:40:29.0: Start special ACP AutoFocus script for scheduler
2019-10-21 07:40:29.0: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 07:40:29.0:   Script ACP-Weather is loaded, and
2019-10-21 07:40:29.0:   the console is idle.
2019-10-21 07:40:29.0:   In start loop, i = 0
2019-10-21 07:40:29.0:   Console is now idle
2019-10-21 07:40:29.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 07:40:29.1:   Script started successfully
2019-10-21 07:40:39.1: Sequencer is now active
2019-10-21 07:45:52.8: Sequencer is no longer active
2019-10-21 07:45:52.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-21 07:45:53.3: SelectRunning: No running plans
2019-10-21 07:45:53.3: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2019-10-21 07:45:53.3:     AirMass estimates ready after 21-Oct-2019 07:48:18 UTC
2019-10-21 07:45:53.3: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2019-10-21 07:45:53.3:     AirMass estimates ready after 21-Oct-2019 07:48:18 UTC
2019-10-21 07:45:53.3: Obs SA109 in Plan SA109 was vetoed by AirMass constraint.
2019-10-21 07:45:53.3:     AirMass estimates ready after 21-Oct-2019 19:18:37 UTC
2019-10-21 07:45:53.3: Efficiency: Only one pending plan Test, it is the "best"
2019-10-21 07:45:53.4: Dispatcher cycle time: 0.6 sec.
2019-10-21 07:45:53.4: Acquire data for Observation Test...
2019-10-21 07:45:53.4:   (belongs to Project KH15D, Plan Test)
2019-10-21 07:45:53.4: ++ Auto Focus ++
2019-10-21 07:45:53.4: Doing periodic autofocus.
2019-10-21 07:45:53.4: Start special ACP AutoFocus script for scheduler
2019-10-21 07:45:53.4: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 07:45:53.4:   Script AutoFocusScheduler is loaded, and
2019-10-21 07:45:53.4:   the console is idle.
2019-10-21 07:45:53.4:   In start loop, i = 0
2019-10-21 07:45:53.4:   Console is now idle
2019-10-21 07:45:53.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 07:45:53.4:   Script started successfully
2019-10-21 07:46:03.5: Sequencer is now active
2019-10-21 07:49:28.0: Sequencer is no longer active
2019-10-21 07:49:28.0: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-10-21 07:49:28.1: Send Observation Test to ACP Sequencer
2019-10-21 07:49:28.1: ACP.RunScript(AcquireScheduler, ):
2019-10-21 07:49:28.1:   Script AutoFocusScheduler is loaded, and
2019-10-21 07:49:28.1:   the console is idle.
2019-10-21 07:49:28.1:   In start loop, i = 0
2019-10-21 07:49:28.1:   Console is now idle
2019-10-21 07:49:28.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-21 07:49:28.4:   Script started successfully
2019-10-21 07:49:38.4: Sequencer is now active
2019-10-21 08:02:13.5: Sequencer is no longer active
2019-10-21 08:02:13.5: Post-job status check done (stat=Completed)
2019-10-21 08:02:13.5: Acquisition time: 765.4102154 sec.
2019-10-21 08:02:13.5: Data for Observation Test of Plan Test acquired successfully.
2019-10-21 08:02:13.5: Plan Test completed successfully.
2019-10-21 08:02:13.5: Image Efficiency: 78.3%
2019-10-21 08:02:13.5: Cycle Efficiency: 99.9%
2019-10-21 08:02:13.6: ++ Auto Focus ++
2019-10-21 08:02:13.6: Doing periodic autofocus.
2019-10-21 08:02:13.7: Start special ACP AutoFocus script for scheduler
2019-10-21 08:02:13.7: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 08:02:13.7:   Script AcquireScheduler is loaded, and
2019-10-21 08:02:13.7:   the console is idle.
2019-10-21 08:02:13.7:   In start loop, i = 0
2019-10-21 08:02:13.7:   Console is now idle
2019-10-21 08:02:13.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 08:02:13.7:   Script started successfully
2019-10-21 08:02:23.7: Sequencer is now active
2019-10-21 08:06:18.1: Sequencer is no longer active
2019-10-21 08:06:18.1: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2019-10-21 08:06:18.1: SelectRunning: No running plans
2019-10-21 08:06:18.1: Obs KH15D in Plan KH15D was vetoed by HourAngle constraint.
2019-10-21 08:06:18.1:     HourAngle estimates ready after 21-Oct-2019 09:20:04 UTC
2019-10-21 08:06:18.1: Obs KH15D-B in Plan KH15D-B was vetoed by HourAngle constraint.
2019-10-21 08:06:18.1:     HourAngle estimates ready after 21-Oct-2019 10:19:54 UTC
2019-10-21 08:06:18.1: No pending plans that can be started now.
2019-10-21 08:06:18.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:06:48.7: SelectRunning: No running plans
2019-10-21 08:06:48.7: No pending plans that can be started now.
2019-10-21 08:06:48.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:07:19.3: SelectRunning: No running plans
2019-10-21 08:07:19.3: No pending plans that can be started now.
2019-10-21 08:07:19.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:07:49.9: SelectRunning: No running plans
2019-10-21 08:07:49.9: No pending plans that can be started now.
2019-10-21 08:07:49.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:08:20.5: SelectRunning: No running plans
2019-10-21 08:08:20.5: No pending plans that can be started now.
2019-10-21 08:08:20.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:08:51.0: SelectRunning: No running plans
2019-10-21 08:08:51.0: No pending plans that can be started now.
2019-10-21 08:08:51.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:09:21.6: SelectRunning: No running plans
2019-10-21 08:09:21.6: No pending plans that can be started now.
2019-10-21 08:09:21.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:09:52.2: SelectRunning: No running plans
2019-10-21 08:09:52.2: No pending plans that can be started now.
2019-10-21 08:09:52.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:10:23.0: SelectRunning: No running plans
2019-10-21 08:10:23.0: No pending plans that can be started now.
2019-10-21 08:10:23.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:10:53.6: SelectRunning: No running plans
2019-10-21 08:10:53.6: No pending plans that can be started now.
2019-10-21 08:10:53.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:11:24.2: SelectRunning: No running plans
2019-10-21 08:11:24.2: No pending plans that can be started now.
2019-10-21 08:11:24.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:11:55.1: SelectRunning: No running plans
2019-10-21 08:11:55.1: No pending plans that can be started now.
2019-10-21 08:11:55.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:12:25.7: SelectRunning: No running plans
2019-10-21 08:12:25.7: No pending plans that can be started now.
2019-10-21 08:12:25.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:12:56.3: SelectRunning: No running plans
2019-10-21 08:12:56.3: No pending plans that can be started now.
2019-10-21 08:12:56.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:13:27.2: SelectRunning: No running plans
2019-10-21 08:13:27.2: No pending plans that can be started now.
2019-10-21 08:13:27.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:13:57.8: SelectRunning: No running plans
2019-10-21 08:13:57.8: No pending plans that can be started now.
2019-10-21 08:13:57.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:14:28.4: SelectRunning: No running plans
2019-10-21 08:14:28.4: No pending plans that can be started now.
2019-10-21 08:14:28.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:14:59.4: SelectRunning: No running plans
2019-10-21 08:14:59.4: No pending plans that can be started now.
2019-10-21 08:14:59.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:15:30.0: SelectRunning: No running plans
2019-10-21 08:15:30.0: No pending plans that can be started now.
2019-10-21 08:15:30.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:16:00.6: SelectRunning: No running plans
2019-10-21 08:16:00.6: No pending plans that can be started now.
2019-10-21 08:16:00.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:16:31.5: ++ Auto Focus ++
2019-10-21 08:16:31.5: Doing periodic autofocus.
2019-10-21 08:16:31.5: Start special ACP AutoFocus script for scheduler
2019-10-21 08:16:31.5: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 08:16:31.5:   Script AutoFocusScheduler is loaded, and
2019-10-21 08:16:31.5:   the console is idle.
2019-10-21 08:16:31.5:   In start loop, i = 0
2019-10-21 08:16:31.5:   Console is now idle
2019-10-21 08:16:31.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 08:16:31.5:   Script started successfully
2019-10-21 08:16:41.6: Sequencer is now active
2019-10-21 08:19:43.9: Sequencer is no longer active
2019-10-21 08:19:43.9: ** Autofocus failed (n=4), will retry ASAP after 15 min.
2019-10-21 08:19:44.0: SelectRunning: No running plans
2019-10-21 08:19:44.0: No pending plans that can be started now.
2019-10-21 08:19:44.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:20:14.5: SelectRunning: No running plans
2019-10-21 08:20:14.6: No pending plans that can be started now.
2019-10-21 08:20:14.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:20:45.1: SelectRunning: No running plans
2019-10-21 08:20:45.1: No pending plans that can be started now.
2019-10-21 08:20:45.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:21:15.7: SelectRunning: No running plans
2019-10-21 08:21:15.7: No pending plans that can be started now.
2019-10-21 08:21:15.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:21:46.3: SelectRunning: No running plans
2019-10-21 08:21:46.3: No pending plans that can be started now.
2019-10-21 08:21:46.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:22:16.9: SelectRunning: No running plans
2019-10-21 08:22:16.9: No pending plans that can be started now.
2019-10-21 08:22:16.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:22:47.5: SelectRunning: No running plans
2019-10-21 08:22:47.5: No pending plans that can be started now.
2019-10-21 08:22:47.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:23:18.1: SelectRunning: No running plans
2019-10-21 08:23:18.1: No pending plans that can be started now.
2019-10-21 08:23:18.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:23:49.0: SelectRunning: No running plans
2019-10-21 08:23:49.0: No pending plans that can be started now.
2019-10-21 08:23:49.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:24:19.6: SelectRunning: No running plans
2019-10-21 08:24:19.6: No pending plans that can be started now.
2019-10-21 08:24:19.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:24:50.2: SelectRunning: No running plans
2019-10-21 08:24:50.2: No pending plans that can be started now.
2019-10-21 08:24:50.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:25:21.1: SelectRunning: No running plans
2019-10-21 08:25:21.1: No pending plans that can be started now.
2019-10-21 08:25:21.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:25:51.7: SelectRunning: No running plans
2019-10-21 08:25:51.7: No pending plans that can be started now.
2019-10-21 08:25:51.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:26:22.3: SelectRunning: No running plans
2019-10-21 08:26:22.3: No pending plans that can be started now.
2019-10-21 08:26:22.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:26:53.3: SelectRunning: No running plans
2019-10-21 08:26:53.3: No pending plans that can be started now.
2019-10-21 08:26:53.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:27:23.8: SelectRunning: No running plans
2019-10-21 08:27:23.8: No pending plans that can be started now.
2019-10-21 08:27:23.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:27:54.4: SelectRunning: No running plans
2019-10-21 08:27:54.4: No pending plans that can be started now.
2019-10-21 08:27:54.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:28:25.0: SelectRunning: No running plans
2019-10-21 08:28:25.0: No pending plans that can be started now.
2019-10-21 08:28:25.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:28:55.6: SelectRunning: No running plans
2019-10-21 08:28:55.6: No pending plans that can be started now.
2019-10-21 08:28:55.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:29:26.2: SelectRunning: No running plans
2019-10-21 08:29:26.2: No pending plans that can be started now.
2019-10-21 08:29:26.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:29:56.8: SelectRunning: No running plans
2019-10-21 08:29:56.8: No pending plans that can be started now.
2019-10-21 08:29:56.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:30:27.4: SelectRunning: No running plans
2019-10-21 08:30:27.4: No pending plans that can be started now.
2019-10-21 08:30:27.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:30:58.0: SelectRunning: No running plans
2019-10-21 08:30:58.0: No pending plans that can be started now.
2019-10-21 08:30:58.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:31:28.6: SelectRunning: No running plans
2019-10-21 08:31:28.6: No pending plans that can be started now.
2019-10-21 08:31:28.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:31:59.1: SelectRunning: No running plans
2019-10-21 08:31:59.2: No pending plans that can be started now.
2019-10-21 08:31:59.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:32:29.7: SelectRunning: No running plans
2019-10-21 08:32:29.7: No pending plans that can be started now.
2019-10-21 08:32:29.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:33:00.3: SelectRunning: No running plans
2019-10-21 08:33:00.3: No pending plans that can be started now.
2019-10-21 08:33:00.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:33:30.9: SelectRunning: No running plans
2019-10-21 08:33:30.9: No pending plans that can be started now.
2019-10-21 08:33:30.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:34:01.6: SelectRunning: No running plans
2019-10-21 08:34:01.6: No pending plans that can be started now.
2019-10-21 08:34:01.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:34:32.2: SelectRunning: No running plans
2019-10-21 08:34:32.2: No pending plans that can be started now.
2019-10-21 08:34:32.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:35:02.8: ++ Auto Focus ++
2019-10-21 08:35:02.8: Doing periodic autofocus.
2019-10-21 08:35:02.8: Start special ACP AutoFocus script for scheduler
2019-10-21 08:35:02.8: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 08:35:02.8:   Script AutoFocusScheduler is loaded, and
2019-10-21 08:35:02.8:   the console is idle.
2019-10-21 08:35:02.8:   In start loop, i = 0
2019-10-21 08:35:02.8:   Console is now idle
2019-10-21 08:35:02.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 08:35:02.8:   Script started successfully
2019-10-21 08:35:12.8: Sequencer is now active
2019-10-21 08:38:40.8: Sequencer is no longer active
2019-10-21 08:38:40.8: ** Autofocus failed (n=5), will retry ASAP after 20 min.
2019-10-21 08:38:40.8: SelectRunning: No running plans
2019-10-21 08:38:40.8: No pending plans that can be started now.
2019-10-21 08:38:40.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:39:11.4: SelectRunning: No running plans
2019-10-21 08:39:11.4: No pending plans that can be started now.
2019-10-21 08:39:11.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:39:41.9: SelectRunning: No running plans
2019-10-21 08:39:42.0: No pending plans that can be started now.
2019-10-21 08:39:42.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:40:12.5: SelectRunning: No running plans
2019-10-21 08:40:12.5: No pending plans that can be started now.
2019-10-21 08:40:12.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:40:43.2: SelectRunning: No running plans
2019-10-21 08:40:43.2: No pending plans that can be started now.
2019-10-21 08:40:43.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:41:13.7: SelectRunning: No running plans
2019-10-21 08:41:13.7: No pending plans that can be started now.
2019-10-21 08:41:13.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:41:44.3: SelectRunning: No running plans
2019-10-21 08:41:44.3: No pending plans that can be started now.
2019-10-21 08:41:44.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:42:14.9: SelectRunning: No running plans
2019-10-21 08:42:14.9: No pending plans that can be started now.
2019-10-21 08:42:14.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:42:45.5: SelectRunning: No running plans
2019-10-21 08:42:45.5: No pending plans that can be started now.
2019-10-21 08:42:45.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:43:16.1: SelectRunning: No running plans
2019-10-21 08:43:16.1: No pending plans that can be started now.
2019-10-21 08:43:16.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:43:46.7: SelectRunning: No running plans
2019-10-21 08:43:46.7: No pending plans that can be started now.
2019-10-21 08:43:46.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:44:17.3: SelectRunning: No running plans
2019-10-21 08:44:17.3: No pending plans that can be started now.
2019-10-21 08:44:17.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:44:47.9: SelectRunning: No running plans
2019-10-21 08:44:47.9: No pending plans that can be started now.
2019-10-21 08:44:47.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:45:18.5: SelectRunning: No running plans
2019-10-21 08:45:18.5: No pending plans that can be started now.
2019-10-21 08:45:18.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:45:49.5: SelectRunning: No running plans
2019-10-21 08:45:49.5: No pending plans that can be started now.
2019-10-21 08:45:49.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:46:20.0: SelectRunning: No running plans
2019-10-21 08:46:20.0: No pending plans that can be started now.
2019-10-21 08:46:20.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:46:50.6: SelectRunning: No running plans
2019-10-21 08:46:50.6: No pending plans that can be started now.
2019-10-21 08:46:50.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:47:21.6: SelectRunning: No running plans
2019-10-21 08:47:21.6: No pending plans that can be started now.
2019-10-21 08:47:21.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:47:52.2: SelectRunning: No running plans
2019-10-21 08:47:52.2: No pending plans that can be started now.
2019-10-21 08:47:52.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:48:22.7: SelectRunning: No running plans
2019-10-21 08:48:22.7: No pending plans that can be started now.
2019-10-21 08:48:22.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:48:53.7: SelectRunning: No running plans
2019-10-21 08:48:53.7: No pending plans that can be started now.
2019-10-21 08:48:53.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:49:24.3: SelectRunning: No running plans
2019-10-21 08:49:24.3: No pending plans that can be started now.
2019-10-21 08:49:24.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:49:54.9: SelectRunning: No running plans
2019-10-21 08:49:54.9: No pending plans that can be started now.
2019-10-21 08:49:54.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:50:25.8: SelectRunning: No running plans
2019-10-21 08:50:25.8: No pending plans that can be started now.
2019-10-21 08:50:25.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:50:56.4: SelectRunning: No running plans
2019-10-21 08:50:56.4: No pending plans that can be started now.
2019-10-21 08:50:56.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:51:27.0: SelectRunning: No running plans
2019-10-21 08:51:27.0: No pending plans that can be started now.
2019-10-21 08:51:27.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:51:57.9: SelectRunning: No running plans
2019-10-21 08:51:57.9: No pending plans that can be started now.
2019-10-21 08:51:57.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:52:28.5: SelectRunning: No running plans
2019-10-21 08:52:28.5: No pending plans that can be started now.
2019-10-21 08:52:28.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:52:59.1: SelectRunning: No running plans
2019-10-21 08:52:59.1: No pending plans that can be started now.
2019-10-21 08:52:59.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:53:30.1: SelectRunning: No running plans
2019-10-21 08:53:30.1: No pending plans that can be started now.
2019-10-21 08:53:30.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:54:00.7: SelectRunning: No running plans
2019-10-21 08:54:00.7: No pending plans that can be started now.
2019-10-21 08:54:00.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:54:31.3: SelectRunning: No running plans
2019-10-21 08:54:31.3: No pending plans that can be started now.
2019-10-21 08:54:31.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:55:02.2: SelectRunning: No running plans
2019-10-21 08:55:02.2: No pending plans that can be started now.
2019-10-21 08:55:02.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:55:32.8: SelectRunning: No running plans
2019-10-21 08:55:32.8: No pending plans that can be started now.
2019-10-21 08:55:32.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:56:03.4: SelectRunning: No running plans
2019-10-21 08:56:03.4: No pending plans that can be started now.
2019-10-21 08:56:03.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:56:34.3: SelectRunning: No running plans
2019-10-21 08:56:34.3: No pending plans that can be started now.
2019-10-21 08:56:34.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:57:04.9: SelectRunning: No running plans
2019-10-21 08:57:04.9: No pending plans that can be started now.
2019-10-21 08:57:04.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:57:35.5: SelectRunning: No running plans
2019-10-21 08:57:35.5: No pending plans that can be started now.
2019-10-21 08:57:35.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:58:06.4: SelectRunning: No running plans
2019-10-21 08:58:06.4: No pending plans that can be started now.
2019-10-21 08:58:06.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:58:37.0: SelectRunning: No running plans
2019-10-21 08:58:37.0: No pending plans that can be started now.
2019-10-21 08:58:37.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 08:59:07.6: ++ Auto Focus ++
2019-10-21 08:59:07.7: Doing periodic autofocus.
2019-10-21 08:59:07.7: Start special ACP AutoFocus script for scheduler
2019-10-21 08:59:07.7: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 08:59:07.7:   Script AutoFocusScheduler is loaded, and
2019-10-21 08:59:07.7:   the console is idle.
2019-10-21 08:59:07.7:   In start loop, i = 0
2019-10-21 08:59:07.7:   Console is now idle
2019-10-21 08:59:07.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 08:59:07.7:   Script started successfully
2019-10-21 08:59:17.7: Sequencer is now active
2019-10-21 09:02:50.5: Sequencer is no longer active
2019-10-21 09:02:50.5: ** Autofocus failed (n=6), will retry ASAP after 20 min.
2019-10-21 09:02:50.5: SelectRunning: No running plans
2019-10-21 09:02:50.5: No pending plans that can be started now.
2019-10-21 09:02:50.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:03:21.1: SelectRunning: No running plans
2019-10-21 09:03:21.1: No pending plans that can be started now.
2019-10-21 09:03:21.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:03:51.7: SelectRunning: No running plans
2019-10-21 09:03:51.7: No pending plans that can be started now.
2019-10-21 09:03:51.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:04:22.2: SelectRunning: No running plans
2019-10-21 09:04:22.2: No pending plans that can be started now.
2019-10-21 09:04:22.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:04:52.8: SelectRunning: No running plans
2019-10-21 09:04:52.8: No pending plans that can be started now.
2019-10-21 09:04:52.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:05:23.4: SelectRunning: No running plans
2019-10-21 09:05:23.4: No pending plans that can be started now.
2019-10-21 09:05:23.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:05:54.0: SelectRunning: No running plans
2019-10-21 09:05:54.0: No pending plans that can be started now.
2019-10-21 09:05:54.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:06:24.6: SelectRunning: No running plans
2019-10-21 09:06:24.6: No pending plans that can be started now.
2019-10-21 09:06:24.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:06:55.6: SelectRunning: No running plans
2019-10-21 09:06:55.6: No pending plans that can be started now.
2019-10-21 09:06:55.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:07:26.2: SelectRunning: No running plans
2019-10-21 09:07:26.2: No pending plans that can be started now.
2019-10-21 09:07:26.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:07:56.8: SelectRunning: No running plans
2019-10-21 09:07:56.8: No pending plans that can be started now.
2019-10-21 09:07:56.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:08:27.7: SelectRunning: No running plans
2019-10-21 09:08:27.7: No pending plans that can be started now.
2019-10-21 09:08:27.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:08:58.3: SelectRunning: No running plans
2019-10-21 09:08:58.3: No pending plans that can be started now.
2019-10-21 09:08:58.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:09:28.9: SelectRunning: No running plans
2019-10-21 09:09:28.9: No pending plans that can be started now.
2019-10-21 09:09:28.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:09:59.8: SelectRunning: No running plans
2019-10-21 09:09:59.8: No pending plans that can be started now.
2019-10-21 09:09:59.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:10:30.4: SelectRunning: No running plans
2019-10-21 09:10:30.4: No pending plans that can be started now.
2019-10-21 09:10:30.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:11:01.0: SelectRunning: No running plans
2019-10-21 09:11:01.0: No pending plans that can be started now.
2019-10-21 09:11:01.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:11:32.0: SelectRunning: No running plans
2019-10-21 09:11:32.0: No pending plans that can be started now.
2019-10-21 09:11:32.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:12:02.6: SelectRunning: No running plans
2019-10-21 09:12:02.6: No pending plans that can be started now.
2019-10-21 09:12:02.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:12:33.2: SelectRunning: No running plans
2019-10-21 09:12:33.2: No pending plans that can be started now.
2019-10-21 09:12:33.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:13:03.7: SelectRunning: No running plans
2019-10-21 09:13:03.7: No pending plans that can be started now.
2019-10-21 09:13:03.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:13:34.3: SelectRunning: No running plans
2019-10-21 09:13:34.3: No pending plans that can be started now.
2019-10-21 09:13:34.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:14:04.9: SelectRunning: No running plans
2019-10-21 09:14:04.9: No pending plans that can be started now.
2019-10-21 09:14:04.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:14:35.5: SelectRunning: No running plans
2019-10-21 09:14:35.5: No pending plans that can be started now.
2019-10-21 09:14:35.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:15:06.1: SelectRunning: No running plans
2019-10-21 09:15:06.1: No pending plans that can be started now.
2019-10-21 09:15:06.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:15:36.7: SelectRunning: No running plans
2019-10-21 09:15:36.7: No pending plans that can be started now.
2019-10-21 09:15:36.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:16:07.3: SelectRunning: No running plans
2019-10-21 09:16:07.3: No pending plans that can be started now.
2019-10-21 09:16:07.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:16:37.9: SelectRunning: No running plans
2019-10-21 09:16:37.9: No pending plans that can be started now.
2019-10-21 09:16:37.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:17:08.5: SelectRunning: No running plans
2019-10-21 09:17:08.5: No pending plans that can be started now.
2019-10-21 09:17:08.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:17:39.1: SelectRunning: No running plans
2019-10-21 09:17:39.1: No pending plans that can be started now.
2019-10-21 09:17:39.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:18:09.6: SelectRunning: No running plans
2019-10-21 09:18:09.6: No pending plans that can be started now.
2019-10-21 09:18:09.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:18:40.3: SelectRunning: No running plans
2019-10-21 09:18:40.3: No pending plans that can be started now.
2019-10-21 09:18:40.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:19:10.9: SelectRunning: No running plans
2019-10-21 09:19:10.9: No pending plans that can be started now.
2019-10-21 09:19:10.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:19:41.5: SelectRunning: No running plans
2019-10-21 09:19:41.5: No pending plans that can be started now.
2019-10-21 09:19:41.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 09:20:12.4: SelectRunning: No running plans
2019-10-21 09:20:12.5: Efficiency: Only one pending plan KH15D, it is the "best"
2019-10-21 09:20:12.5: Dispatcher cycle time: 0.1 sec.
2019-10-21 09:20:12.5: Acquire data for Observation KH15D...
2019-10-21 09:20:12.5:   (belongs to Project KH15D, Plan KH15D)
2019-10-21 09:20:12.5: Send Observation KH15D to ACP Sequencer
2019-10-21 09:20:12.5: ACP.RunScript(AcquireScheduler, ):
2019-10-21 09:20:12.5:   Script AutoFocusScheduler is loaded, and
2019-10-21 09:20:12.5:   the console is idle.
2019-10-21 09:20:12.5:   In start loop, i = 0
2019-10-21 09:20:12.5:   Console is now idle
2019-10-21 09:20:12.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-21 09:20:12.6:   Script started successfully
2019-10-21 09:20:22.6: Sequencer is now active
2019-10-21 10:28:28.1: Sequencer is no longer active
2019-10-21 10:28:28.1: Post-job status check done (stat=Completed)
2019-10-21 10:28:28.1: Acquisition time: 4095.5188142 sec.
2019-10-21 10:28:28.1: Data for Observation KH15D of Plan KH15D acquired successfully.
2019-10-21 10:28:28.1: Plan KH15D completed successfully.
2019-10-21 10:28:28.1: Image Efficiency: 87.9%
2019-10-21 10:28:28.1: Cycle Efficiency: 100.0%
2019-10-21 10:28:28.2: ++ Auto Focus ++
2019-10-21 10:28:28.3: Doing periodic autofocus.
2019-10-21 10:28:28.3: Start special ACP AutoFocus script for scheduler
2019-10-21 10:28:28.3: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 10:28:28.3:   Script AcquireScheduler is loaded, and
2019-10-21 10:28:28.3:   the console is idle.
2019-10-21 10:28:28.3:   In start loop, i = 0
2019-10-21 10:28:28.3:   Console is now idle
2019-10-21 10:28:28.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 10:28:28.3:   Script started successfully
2019-10-21 10:28:38.3: Sequencer is now active
2019-10-21 10:32:46.9: Sequencer is no longer active
2019-10-21 10:32:46.9: ** Autofocus failed (n=7), will retry ASAP after 20 min.
2019-10-21 10:32:46.9: SelectRunning: No running plans
2019-10-21 10:32:46.9: Efficiency: Only one pending plan KH15D-B, it is the "best"
2019-10-21 10:32:47.0: Dispatcher cycle time: 0.1 sec.
2019-10-21 10:32:47.0: Acquire data for Observation KH15D-B...
2019-10-21 10:32:47.0:   (belongs to Project KH15D, Plan KH15D-B)
2019-10-21 10:32:47.0: Send Observation KH15D-B to ACP Sequencer
2019-10-21 10:32:47.0: ACP.RunScript(AcquireScheduler, ):
2019-10-21 10:32:47.0:   Script AutoFocusScheduler is loaded, and
2019-10-21 10:32:47.0:   the console is idle.
2019-10-21 10:32:47.0:   In start loop, i = 0
2019-10-21 10:32:47.0:   Console is now idle
2019-10-21 10:32:47.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-21 10:32:47.0:   Script started successfully
2019-10-21 10:32:57.1: Sequencer is now active
2019-10-21 11:10:32.0: Sequencer is no longer active
2019-10-21 11:10:32.0: Post-job status check done (stat=Completed)
2019-10-21 11:10:32.0: Observation's requested AF failed. Next periodic AF still 21-Oct-2019 10:52:46 UTC
2019-10-21 11:10:32.0: Acquisition time: 2264.9973983 sec.
2019-10-21 11:10:32.0: Data for Observation KH15D-B of Plan KH15D-B acquired successfully.
2019-10-21 11:10:32.0: Plan KH15D-B completed successfully.
2019-10-21 11:10:32.0: Image Efficiency: 79.5%
2019-10-21 11:10:32.0: Cycle Efficiency: 100.0%
2019-10-21 11:10:32.2: ++ Auto Focus ++
2019-10-21 11:10:32.2: Doing periodic autofocus.
2019-10-21 11:10:32.2: Start special ACP AutoFocus script for scheduler
2019-10-21 11:10:32.2: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 11:10:32.2:   Script AcquireScheduler is loaded, and
2019-10-21 11:10:32.2:   the console is idle.
2019-10-21 11:10:32.2:   In start loop, i = 0
2019-10-21 11:10:32.2:   Console is now idle
2019-10-21 11:10:32.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 11:10:32.2:   Script started successfully
2019-10-21 11:10:42.2: Sequencer is now active
2019-10-21 11:14:41.9: Sequencer is no longer active
2019-10-21 11:14:41.9: ** Autofocus failed (n=8), will retry ASAP after 20 min.
2019-10-21 11:14:41.9: SelectRunning: No running plans
2019-10-21 11:14:41.9: No pending plans that can be started now.
2019-10-21 11:14:41.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:15:12.5: SelectRunning: No running plans
2019-10-21 11:15:12.5: No pending plans that can be started now.
2019-10-21 11:15:12.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:15:43.1: SelectRunning: No running plans
2019-10-21 11:15:43.1: No pending plans that can be started now.
2019-10-21 11:15:43.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:16:14.0: SelectRunning: No running plans
2019-10-21 11:16:14.0: No pending plans that can be started now.
2019-10-21 11:16:14.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:16:44.6: SelectRunning: No running plans
2019-10-21 11:16:44.6: No pending plans that can be started now.
2019-10-21 11:16:44.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:17:15.2: SelectRunning: No running plans
2019-10-21 11:17:15.2: No pending plans that can be started now.
2019-10-21 11:17:15.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:17:46.2: SelectRunning: No running plans
2019-10-21 11:17:46.2: No pending plans that can be started now.
2019-10-21 11:17:46.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:18:16.7: SelectRunning: No running plans
2019-10-21 11:18:16.7: No pending plans that can be started now.
2019-10-21 11:18:16.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:18:47.3: SelectRunning: No running plans
2019-10-21 11:18:47.3: No pending plans that can be started now.
2019-10-21 11:18:47.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:19:18.3: SelectRunning: No running plans
2019-10-21 11:19:18.3: No pending plans that can be started now.
2019-10-21 11:19:18.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:19:48.9: SelectRunning: No running plans
2019-10-21 11:19:48.9: No pending plans that can be started now.
2019-10-21 11:19:48.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:20:19.5: SelectRunning: No running plans
2019-10-21 11:20:19.5: No pending plans that can be started now.
2019-10-21 11:20:19.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:20:50.4: SelectRunning: No running plans
2019-10-21 11:20:50.4: No pending plans that can be started now.
2019-10-21 11:20:50.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:21:21.0: SelectRunning: No running plans
2019-10-21 11:21:21.0: No pending plans that can be started now.
2019-10-21 11:21:21.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:21:51.6: SelectRunning: No running plans
2019-10-21 11:21:51.6: No pending plans that can be started now.
2019-10-21 11:21:51.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:22:22.5: SelectRunning: No running plans
2019-10-21 11:22:22.5: No pending plans that can be started now.
2019-10-21 11:22:22.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:22:53.1: SelectRunning: No running plans
2019-10-21 11:22:53.1: No pending plans that can be started now.
2019-10-21 11:22:53.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:23:23.7: SelectRunning: No running plans
2019-10-21 11:23:23.7: No pending plans that can be started now.
2019-10-21 11:23:23.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:23:54.6: SelectRunning: No running plans
2019-10-21 11:23:54.6: No pending plans that can be started now.
2019-10-21 11:23:54.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:24:25.2: SelectRunning: No running plans
2019-10-21 11:24:25.2: No pending plans that can be started now.
2019-10-21 11:24:25.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:24:55.8: SelectRunning: No running plans
2019-10-21 11:24:55.8: No pending plans that can be started now.
2019-10-21 11:24:55.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:25:26.4: SelectRunning: No running plans
2019-10-21 11:25:26.4: No pending plans that can be started now.
2019-10-21 11:25:26.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:25:57.0: SelectRunning: No running plans
2019-10-21 11:25:57.0: No pending plans that can be started now.
2019-10-21 11:25:57.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:26:27.6: SelectRunning: No running plans
2019-10-21 11:26:27.6: No pending plans that can be started now.
2019-10-21 11:26:27.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:26:58.2: SelectRunning: No running plans
2019-10-21 11:26:58.2: No pending plans that can be started now.
2019-10-21 11:26:58.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:27:28.8: SelectRunning: No running plans
2019-10-21 11:27:28.8: No pending plans that can be started now.
2019-10-21 11:27:28.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:27:59.4: SelectRunning: No running plans
2019-10-21 11:27:59.4: No pending plans that can be started now.
2019-10-21 11:27:59.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:28:30.0: SelectRunning: No running plans
2019-10-21 11:28:30.0: No pending plans that can be started now.
2019-10-21 11:28:30.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:29:00.6: SelectRunning: No running plans
2019-10-21 11:29:00.6: No pending plans that can be started now.
2019-10-21 11:29:00.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:29:31.2: SelectRunning: No running plans
2019-10-21 11:29:31.2: No pending plans that can be started now.
2019-10-21 11:29:31.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:30:01.7: SelectRunning: No running plans
2019-10-21 11:30:01.7: No pending plans that can be started now.
2019-10-21 11:30:01.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:30:32.3: SelectRunning: No running plans
2019-10-21 11:30:32.3: No pending plans that can be started now.
2019-10-21 11:30:32.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:31:03.0: SelectRunning: No running plans
2019-10-21 11:31:03.0: No pending plans that can be started now.
2019-10-21 11:31:03.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:31:33.6: SelectRunning: No running plans
2019-10-21 11:31:33.6: No pending plans that can be started now.
2019-10-21 11:31:33.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:32:04.2: SelectRunning: No running plans
2019-10-21 11:32:04.2: No pending plans that can be started now.
2019-10-21 11:32:04.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:32:35.1: SelectRunning: No running plans
2019-10-21 11:32:35.2: No pending plans that can be started now.
2019-10-21 11:32:35.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:33:05.7: SelectRunning: No running plans
2019-10-21 11:33:05.7: No pending plans that can be started now.
2019-10-21 11:33:05.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:33:36.3: SelectRunning: No running plans
2019-10-21 11:33:36.3: No pending plans that can be started now.
2019-10-21 11:33:36.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:34:07.3: SelectRunning: No running plans
2019-10-21 11:34:07.3: No pending plans that can be started now.
2019-10-21 11:34:07.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:34:37.9: SelectRunning: No running plans
2019-10-21 11:34:37.9: No pending plans that can be started now.
2019-10-21 11:34:37.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:35:08.5: ++ Auto Focus ++
2019-10-21 11:35:08.5: Doing periodic autofocus.
2019-10-21 11:35:08.5: Start special ACP AutoFocus script for scheduler
2019-10-21 11:35:08.5: ACP.RunScript(AutoFocusScheduler, ):
2019-10-21 11:35:08.5:   Script AutoFocusScheduler is loaded, and
2019-10-21 11:35:08.5:   the console is idle.
2019-10-21 11:35:08.5:   In start loop, i = 0
2019-10-21 11:35:08.5:   Console is now idle
2019-10-21 11:35:08.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-21 11:35:08.5:   Script started successfully
2019-10-21 11:35:18.5: Sequencer is now active
2019-10-21 11:38:30.8: Sequencer is no longer active
2019-10-21 11:38:30.8: Next periodic autofocus ASAP after 3600sec. 21-Oct-2019 12:38:11 UTC
2019-10-21 11:38:30.9: SelectRunning: No running plans
2019-10-21 11:38:30.9: No pending plans that can be started now.
2019-10-21 11:38:30.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:39:01.4: SelectRunning: No running plans
2019-10-21 11:39:01.5: No pending plans that can be started now.
2019-10-21 11:39:01.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:39:32.1: SelectRunning: No running plans
2019-10-21 11:39:32.1: No pending plans that can be started now.
2019-10-21 11:39:32.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:40:02.6: SelectRunning: No running plans
2019-10-21 11:40:02.6: No pending plans that can be started now.
2019-10-21 11:40:02.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:40:33.2: SelectRunning: No running plans
2019-10-21 11:40:33.2: No pending plans that can be started now.
2019-10-21 11:40:33.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:41:03.8: SelectRunning: No running plans
2019-10-21 11:41:03.8: No pending plans that can be started now.
2019-10-21 11:41:03.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:41:34.4: SelectRunning: No running plans
2019-10-21 11:41:34.4: No pending plans that can be started now.
2019-10-21 11:41:34.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:42:05.0: SelectRunning: No running plans
2019-10-21 11:42:05.0: No pending plans that can be started now.
2019-10-21 11:42:05.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:42:36.0: SelectRunning: No running plans
2019-10-21 11:42:36.0: No pending plans that can be started now.
2019-10-21 11:42:36.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:43:06.6: SelectRunning: No running plans
2019-10-21 11:43:06.6: No pending plans that can be started now.
2019-10-21 11:43:06.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:43:37.2: SelectRunning: No running plans
2019-10-21 11:43:37.2: No pending plans that can be started now.
2019-10-21 11:43:37.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:44:08.1: SelectRunning: No running plans
2019-10-21 11:44:08.1: No pending plans that can be started now.
2019-10-21 11:44:08.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:44:38.7: SelectRunning: No running plans
2019-10-21 11:44:38.7: No pending plans that can be started now.
2019-10-21 11:44:38.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:45:09.3: SelectRunning: No running plans
2019-10-21 11:45:09.3: No pending plans that can be started now.
2019-10-21 11:45:09.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:45:40.2: SelectRunning: No running plans
2019-10-21 11:45:40.2: No pending plans that can be started now.
2019-10-21 11:45:40.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:46:10.8: SelectRunning: No running plans
2019-10-21 11:46:10.8: No pending plans that can be started now.
2019-10-21 11:46:10.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:46:41.4: SelectRunning: No running plans
2019-10-21 11:46:41.4: No pending plans that can be started now.
2019-10-21 11:46:41.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:47:12.4: SelectRunning: No running plans
2019-10-21 11:47:12.4: No pending plans that can be started now.
2019-10-21 11:47:12.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:47:43.0: SelectRunning: No running plans
2019-10-21 11:47:43.0: No pending plans that can be started now.
2019-10-21 11:47:43.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:48:13.6: SelectRunning: No running plans
2019-10-21 11:48:13.6: No pending plans that can be started now.
2019-10-21 11:48:13.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:48:44.5: SelectRunning: No running plans
2019-10-21 11:48:44.5: No pending plans that can be started now.
2019-10-21 11:48:44.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:49:15.1: SelectRunning: No running plans
2019-10-21 11:49:15.1: No pending plans that can be started now.
2019-10-21 11:49:15.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:49:45.7: SelectRunning: No running plans
2019-10-21 11:49:45.7: No pending plans that can be started now.
2019-10-21 11:49:45.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:50:16.6: SelectRunning: No running plans
2019-10-21 11:50:16.6: No pending plans that can be started now.
2019-10-21 11:50:16.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:50:47.2: SelectRunning: No running plans
2019-10-21 11:50:47.2: No pending plans that can be started now.
2019-10-21 11:50:47.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:51:17.8: SelectRunning: No running plans
2019-10-21 11:51:17.8: No pending plans that can be started now.
2019-10-21 11:51:17.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:51:48.7: SelectRunning: No running plans
2019-10-21 11:51:48.7: No pending plans that can be started now.
2019-10-21 11:51:48.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:52:19.3: SelectRunning: No running plans
2019-10-21 11:52:19.3: No pending plans that can be started now.
2019-10-21 11:52:19.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:52:49.9: SelectRunning: No running plans
2019-10-21 11:52:49.9: No pending plans that can be started now.
2019-10-21 11:52:49.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:53:20.8: SelectRunning: No running plans
2019-10-21 11:53:20.8: No pending plans that can be started now.
2019-10-21 11:53:20.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:53:51.4: SelectRunning: No running plans
2019-10-21 11:53:51.4: No pending plans that can be started now.
2019-10-21 11:53:51.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:54:22.0: SelectRunning: No running plans
2019-10-21 11:54:22.0: No pending plans that can be started now.
2019-10-21 11:54:22.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:54:52.9: SelectRunning: No running plans
2019-10-21 11:54:52.9: No pending plans that can be started now.
2019-10-21 11:54:52.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:55:23.5: SelectRunning: No running plans
2019-10-21 11:55:23.5: No pending plans that can be started now.
2019-10-21 11:55:23.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:55:54.1: SelectRunning: No running plans
2019-10-21 11:55:54.1: No pending plans that can be started now.
2019-10-21 11:55:54.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:56:25.1: SelectRunning: No running plans
2019-10-21 11:56:25.1: No pending plans that can be started now.
2019-10-21 11:56:25.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:56:55.7: SelectRunning: No running plans
2019-10-21 11:56:55.7: No pending plans that can be started now.
2019-10-21 11:56:55.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:57:26.3: SelectRunning: No running plans
2019-10-21 11:57:26.3: No pending plans that can be started now.
2019-10-21 11:57:26.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:57:57.2: SelectRunning: No running plans
2019-10-21 11:57:57.2: No pending plans that can be started now.
2019-10-21 11:57:57.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:58:27.8: SelectRunning: No running plans
2019-10-21 11:58:27.8: No pending plans that can be started now.
2019-10-21 11:58:27.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:58:58.4: SelectRunning: No running plans
2019-10-21 11:58:58.4: No pending plans that can be started now.
2019-10-21 11:58:58.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:59:28.9: SelectRunning: No running plans
2019-10-21 11:59:28.9: No pending plans that can be started now.
2019-10-21 11:59:28.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 11:59:59.5: SelectRunning: No running plans
2019-10-21 11:59:59.5: No pending plans that can be started now.
2019-10-21 11:59:59.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:00:30.1: SelectRunning: No running plans
2019-10-21 12:00:30.1: No pending plans that can be started now.
2019-10-21 12:00:30.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:01:00.7: SelectRunning: No running plans
2019-10-21 12:01:00.7: No pending plans that can be started now.
2019-10-21 12:01:00.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:01:31.3: SelectRunning: No running plans
2019-10-21 12:01:31.3: No pending plans that can be started now.
2019-10-21 12:01:31.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:02:01.9: SelectRunning: No running plans
2019-10-21 12:02:01.9: No pending plans that can be started now.
2019-10-21 12:02:01.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:02:32.5: SelectRunning: No running plans
2019-10-21 12:02:32.5: No pending plans that can be started now.
2019-10-21 12:02:32.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:03:03.1: SelectRunning: No running plans
2019-10-21 12:03:03.1: No pending plans that can be started now.
2019-10-21 12:03:03.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:03:33.7: SelectRunning: No running plans
2019-10-21 12:03:33.7: No pending plans that can be started now.
2019-10-21 12:03:33.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:04:04.3: SelectRunning: No running plans
2019-10-21 12:04:04.3: No pending plans that can be started now.
2019-10-21 12:04:04.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:04:34.9: SelectRunning: No running plans
2019-10-21 12:04:34.9: No pending plans that can be started now.
2019-10-21 12:04:34.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:05:05.5: SelectRunning: No running plans
2019-10-21 12:05:05.5: No pending plans that can be started now.
2019-10-21 12:05:05.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:05:36.1: SelectRunning: No running plans
2019-10-21 12:05:36.1: No pending plans that can be started now.
2019-10-21 12:05:36.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:06:06.7: SelectRunning: No running plans
2019-10-21 12:06:06.7: No pending plans that can be started now.
2019-10-21 12:06:06.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:06:37.3: SelectRunning: No running plans
2019-10-21 12:06:37.3: No pending plans that can be started now.
2019-10-21 12:06:37.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:07:07.9: SelectRunning: No running plans
2019-10-21 12:07:07.9: No pending plans that can be started now.
2019-10-21 12:07:07.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:07:38.5: SelectRunning: No running plans
2019-10-21 12:07:38.5: No pending plans that can be started now.
2019-10-21 12:07:38.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:08:09.1: SelectRunning: No running plans
2019-10-21 12:08:09.1: No pending plans that can be started now.
2019-10-21 12:08:09.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:08:39.7: SelectRunning: No running plans
2019-10-21 12:08:39.7: No pending plans that can be started now.
2019-10-21 12:08:39.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:09:10.3: SelectRunning: No running plans
2019-10-21 12:09:10.3: No pending plans that can be started now.
2019-10-21 12:09:10.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:09:40.8: SelectRunning: No running plans
2019-10-21 12:09:40.8: No pending plans that can be started now.
2019-10-21 12:09:40.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:10:11.5: SelectRunning: No running plans
2019-10-21 12:10:11.5: No pending plans that can be started now.
2019-10-21 12:10:11.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:10:42.0: SelectRunning: No running plans
2019-10-21 12:10:42.0: No pending plans that can be started now.
2019-10-21 12:10:42.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:11:12.6: SelectRunning: No running plans
2019-10-21 12:11:12.6: No pending plans that can be started now.
2019-10-21 12:11:12.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:11:43.2: SelectRunning: No running plans
2019-10-21 12:11:43.2: No pending plans that can be started now.
2019-10-21 12:11:43.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:12:13.9: SelectRunning: No running plans
2019-10-21 12:12:13.9: No pending plans that can be started now.
2019-10-21 12:12:13.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:12:44.5: SelectRunning: No running plans
2019-10-21 12:12:44.5: No pending plans that can be started now.
2019-10-21 12:12:44.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:13:15.1: SelectRunning: No running plans
2019-10-21 12:13:15.1: No pending plans that can be started now.
2019-10-21 12:13:15.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:13:46.0: SelectRunning: No running plans
2019-10-21 12:13:46.1: No pending plans that can be started now.
2019-10-21 12:13:46.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:14:16.6: SelectRunning: No running plans
2019-10-21 12:14:16.7: No pending plans that can be started now.
2019-10-21 12:14:16.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:14:47.2: SelectRunning: No running plans
2019-10-21 12:14:47.2: No pending plans that can be started now.
2019-10-21 12:14:47.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:15:18.2: SelectRunning: No running plans
2019-10-21 12:15:18.2: No pending plans that can be started now.
2019-10-21 12:15:18.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:15:48.8: SelectRunning: No running plans
2019-10-21 12:15:48.8: No pending plans that can be started now.
2019-10-21 12:15:48.8: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:16:19.4: SelectRunning: No running plans
2019-10-21 12:16:19.4: No pending plans that can be started now.
2019-10-21 12:16:19.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:16:50.3: SelectRunning: No running plans
2019-10-21 12:16:50.3: No pending plans that can be started now.
2019-10-21 12:16:50.3: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:17:20.9: SelectRunning: No running plans
2019-10-21 12:17:20.9: No pending plans that can be started now.
2019-10-21 12:17:20.9: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:17:51.5: SelectRunning: No running plans
2019-10-21 12:17:51.5: No pending plans that can be started now.
2019-10-21 12:17:51.5: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:18:22.4: SelectRunning: No running plans
2019-10-21 12:18:22.4: No pending plans that can be started now.
2019-10-21 12:18:22.4: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:18:53.0: SelectRunning: No running plans
2019-10-21 12:18:53.0: No pending plans that can be started now.
2019-10-21 12:18:53.0: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:19:23.6: SelectRunning: No running plans
2019-10-21 12:19:23.6: No pending plans that can be started now.
2019-10-21 12:19:23.6: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:19:54.2: SelectRunning: No running plans
2019-10-21 12:19:54.2: No pending plans that can be started now.
2019-10-21 12:19:54.2: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:20:24.7: SelectRunning: No running plans
2019-10-21 12:20:24.7: No pending plans that can be started now.
2019-10-21 12:20:24.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:20:53.1: SelectRunning: No running plans
2019-10-21 12:20:53.1: No pending plans that can be started now.
2019-10-21 12:20:53.1: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:21:23.7: SelectRunning: No running plans
2019-10-21 12:21:23.7: No pending plans that can be started now.
2019-10-21 12:21:23.7: Dispatcher cycle time: 0.0 sec.
2019-10-21 12:33:38.2: ++ Dawn Flats ++
2019-10-21 12:33:38.2: Start ACP AutoFlat script for Dawn flats
2019-10-21 12:33:38.2: ACP.RunScript(AutoFlat, C:\Users\admin\Documents\ACP Astronomy\Plans\SchedulerDawnFlats.txt):
2019-10-21 12:33:38.2:   Script AutoFocusScheduler is loaded, and
2019-10-21 12:33:38.2:   the console is idle.
2019-10-21 12:33:38.2:   In start loop, i = 0
2019-10-21 12:33:38.2:   Console is now idle
2019-10-21 12:33:38.3:   Loaded C:\Program Files (x86)\ACP Obs Control\Scripts\AutoFlat.vbs successfully
2019-10-21 12:33:38.3:   Set parameter "C:\Users\admin\Documents\ACP Astronomy\Plans\SchedulerDawnFlats.txt" successfully
2019-10-21 12:33:38.3:   Script started successfully
2019-10-21 12:33:48.3: Sequencer is now active
2019-10-21 12:52:01.6: ** Script interrupted by weather safety event.
2019-10-21 12:53:24.9: Sequencer is no longer active
2019-10-21 12:53:25.0: -- Weather Unsafe --
2019-10-21 12:53:56.9: -- Observatory Shutdown --
2019-10-21 12:53:56.9: Start ACP Sequencer's ShutdownObs script
2019-10-21 12:53:56.9: ACP.RunScript(ShutdownObs, ):
2019-10-21 12:53:56.9:   Script ACP-Weather is loaded, and
2019-10-21 12:53:56.9:   the console is idle.
2019-10-21 12:53:56.9:   In start loop, i = 0
2019-10-21 12:53:56.9:   Console is now idle
2019-10-21 12:53:57.0:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\ShutdownObs.js successfully
2019-10-21 12:53:57.0:   Script started successfully
2019-10-21 12:54:07.0: Sequencer is now active
2019-10-21 12:54:12.1: Sequencer is no longer active
2019-10-21 19:00:06.5: Run statistics:
2019-10-21 19:00:06.5:   Observations:   1
2019-10-21 19:00:06.5:   Considered:     0
2019-10-21 19:00:06.5:   Completed:      0
2019-10-21 19:00:06.5:   Skipped:        0
2019-10-21 19:00:06.5:   Never Eligible: 1
2019-10-21 19:00:06.5:   Failed:         0
Log closed at Mon, Oct 21 2019 19:00:06 UTC (actual time)