Log opened at Sat, Oct 12 2019 03:08:47 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2019-10-12 03:08:47.2: ++ Observatory Startup ++
2019-10-12 03:08:47.2: Startup attempted, but no StartupObs script found
2019-10-12 03:08:47.2: Open the observatory dome/roof
2019-10-12 03:09:48.0: Observatory startup succeeded, and dome opened successfully
2019-10-12 03:09:48.0: ++ Auto Focus ++
2019-10-12 03:09:48.0: Doing initial autofocus.
2019-10-12 03:09:48.0: Start special ACP AutoFocus script for scheduler
2019-10-12 03:09:48.0: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 03:09:48.0:   Script AutoFocusScheduler is loaded, and
2019-10-12 03:09:48.0:   the console is idle.
2019-10-12 03:09:48.0:   In start loop, i = 0
2019-10-12 03:09:48.0:   Console is now idle
2019-10-12 03:09:48.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 03:09:48.0:   Script started successfully
2019-10-12 03:09:58.0: Sequencer is now active
2019-10-12 03:14:48.4: Sequencer is no longer active
2019-10-12 03:14:48.4: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-10-12 03:14:48.9: SelectRunning: No running plans
2019-10-12 03:14:48.9: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2019-10-12 03:14:48.9:     AirMass estimates ready after 12-Oct-2019 08:23:41 UTC
2019-10-12 03:14:48.9: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2019-10-12 03:14:48.9:     AirMass estimates ready after 12-Oct-2019 08:23:41 UTC
2019-10-12 03:14:48.9: Obs SA109 in Plan SA109 was vetoed by AirMass constraint.
2019-10-12 03:14:48.9:     AirMass estimates ready after 12-Oct-2019 19:54:00 UTC
2019-10-12 03:14:48.9: Efficiency: Only one pending plan Test, it is the "best"
2019-10-12 03:14:49.0: Dispatcher cycle time: 0.2 sec.
2019-10-12 03:14:49.0: Acquire data for Observation Test...
2019-10-12 03:14:49.0:   (belongs to Project KH15D, Plan Test)
2019-10-12 03:14:49.0: ++ Auto Focus ++
2019-10-12 03:14:49.0: Doing periodic autofocus.
2019-10-12 03:14:49.0: Start special ACP AutoFocus script for scheduler
2019-10-12 03:14:49.0: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 03:14:49.0:   Script AutoFocusScheduler is loaded, and
2019-10-12 03:14:49.0:   the console is idle.
2019-10-12 03:14:49.0:   In start loop, i = 0
2019-10-12 03:14:49.0:   Console is now idle
2019-10-12 03:14:49.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 03:14:49.0:   Script started successfully
2019-10-12 03:14:59.0: Sequencer is now active
2019-10-12 03:17:54.0: Sequencer is no longer active
2019-10-12 03:17:54.0: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-10-12 03:17:54.0: Send Observation Test to ACP Sequencer
2019-10-12 03:17:54.0: ACP.RunScript(AcquireScheduler, ):
2019-10-12 03:17:54.0:   Script AutoFocusScheduler is loaded, and
2019-10-12 03:17:54.0:   the console is idle.
2019-10-12 03:17:54.0:   In start loop, i = 0
2019-10-12 03:17:54.0:   Console is now idle
2019-10-12 03:17:54.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-12 03:17:54.0:   Script started successfully
2019-10-12 03:18:04.0: Sequencer is now active
2019-10-12 03:19:57.8: Sequencer is no longer active
2019-10-12 03:19:57.8: Post-job status check done (stat=Failed)
2019-10-12 03:19:57.8: ACP data acquisition failed for Observation Test.
2019-10-12 03:19:57.8:   (Observation Test interrupted by acquisition process failure.)
2019-10-12 03:19:57.9: SelectRunning: No running plans
2019-10-12 03:19:57.9: No pending plans that can be started now.
2019-10-12 03:19:57.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:20:28.5: SelectRunning: No running plans
2019-10-12 03:20:28.5: No pending plans that can be started now.
2019-10-12 03:20:28.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:20:59.2: SelectRunning: No running plans
2019-10-12 03:20:59.2: No pending plans that can be started now.
2019-10-12 03:20:59.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:21:29.7: SelectRunning: No running plans
2019-10-12 03:21:29.7: No pending plans that can be started now.
2019-10-12 03:21:29.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:22:00.3: SelectRunning: No running plans
2019-10-12 03:22:00.3: No pending plans that can be started now.
2019-10-12 03:22:00.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:22:31.3: SelectRunning: No running plans
2019-10-12 03:22:31.3: No pending plans that can be started now.
2019-10-12 03:22:31.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:23:01.9: ++ Auto Focus ++
2019-10-12 03:23:01.9: Doing periodic autofocus.
2019-10-12 03:23:01.9: Start special ACP AutoFocus script for scheduler
2019-10-12 03:23:01.9: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 03:23:01.9:   Script AcquireScheduler is loaded, and
2019-10-12 03:23:01.9:   the console is idle.
2019-10-12 03:23:01.9:   In start loop, i = 0
2019-10-12 03:23:01.9:   Console is now idle
2019-10-12 03:23:01.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 03:23:01.9:   Script started successfully
2019-10-12 03:23:11.9: Sequencer is now active
2019-10-12 03:27:28.7: Sequencer is no longer active
2019-10-12 03:27:28.7: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2019-10-12 03:27:28.7: SelectRunning: No running plans
2019-10-12 03:27:28.7: No pending plans that can be started now.
2019-10-12 03:27:28.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:27:59.2: SelectRunning: No running plans
2019-10-12 03:27:59.3: No pending plans that can be started now.
2019-10-12 03:27:59.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:28:29.8: SelectRunning: No running plans
2019-10-12 03:28:29.9: No pending plans that can be started now.
2019-10-12 03:28:29.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:29:00.4: SelectRunning: No running plans
2019-10-12 03:29:00.4: No pending plans that can be started now.
2019-10-12 03:29:00.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:29:31.0: SelectRunning: No running plans
2019-10-12 03:29:31.0: No pending plans that can be started now.
2019-10-12 03:29:31.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:30:01.6: SelectRunning: No running plans
2019-10-12 03:30:01.6: No pending plans that can be started now.
2019-10-12 03:30:01.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:30:32.2: SelectRunning: No running plans
2019-10-12 03:30:32.2: No pending plans that can be started now.
2019-10-12 03:30:32.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:31:02.8: SelectRunning: No running plans
2019-10-12 03:31:02.8: No pending plans that can be started now.
2019-10-12 03:31:02.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:31:33.7: SelectRunning: No running plans
2019-10-12 03:31:33.7: No pending plans that can be started now.
2019-10-12 03:31:33.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:32:04.3: SelectRunning: No running plans
2019-10-12 03:32:04.3: No pending plans that can be started now.
2019-10-12 03:32:04.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:32:34.8: SelectRunning: No running plans
2019-10-12 03:32:34.8: No pending plans that can be started now.
2019-10-12 03:32:34.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:33:05.8: SelectRunning: No running plans
2019-10-12 03:33:05.8: No pending plans that can be started now.
2019-10-12 03:33:05.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:33:36.4: SelectRunning: No running plans
2019-10-12 03:33:36.4: No pending plans that can be started now.
2019-10-12 03:33:36.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:34:07.0: SelectRunning: No running plans
2019-10-12 03:34:07.0: No pending plans that can be started now.
2019-10-12 03:34:07.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:34:37.9: SelectRunning: No running plans
2019-10-12 03:34:37.9: No pending plans that can be started now.
2019-10-12 03:34:37.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:35:08.5: SelectRunning: No running plans
2019-10-12 03:35:08.5: No pending plans that can be started now.
2019-10-12 03:35:08.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:35:39.1: SelectRunning: No running plans
2019-10-12 03:35:39.1: No pending plans that can be started now.
2019-10-12 03:35:39.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:36:10.0: SelectRunning: No running plans
2019-10-12 03:36:10.0: No pending plans that can be started now.
2019-10-12 03:36:10.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:36:40.6: SelectRunning: No running plans
2019-10-12 03:36:40.6: No pending plans that can be started now.
2019-10-12 03:36:40.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:37:11.2: SelectRunning: No running plans
2019-10-12 03:37:11.2: No pending plans that can be started now.
2019-10-12 03:37:11.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:37:42.2: ++ Auto Focus ++
2019-10-12 03:37:42.2: Doing periodic autofocus.
2019-10-12 03:37:42.2: Start special ACP AutoFocus script for scheduler
2019-10-12 03:37:42.2: ACP.RunScript(AutoFocusScheduler, ):
2019-10-12 03:37:42.2:   Script AutoFocusScheduler is loaded, and
2019-10-12 03:37:42.2:   the console is idle.
2019-10-12 03:37:42.2:   In start loop, i = 0
2019-10-12 03:37:42.2:   Console is now idle
2019-10-12 03:37:42.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2019-10-12 03:37:42.2:   Script started successfully
2019-10-12 03:37:52.2: Sequencer is now active
2019-10-12 03:41:10.7: Sequencer is no longer active
2019-10-12 03:41:10.7: Next periodic autofocus ASAP after 3600sec. 12-Oct-2019 04:40:46 UTC
2019-10-12 03:41:10.7: SelectRunning: No running plans
2019-10-12 03:41:10.7: No pending plans that can be started now.
2019-10-12 03:41:10.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:41:41.3: SelectRunning: No running plans
2019-10-12 03:41:41.3: No pending plans that can be started now.
2019-10-12 03:41:41.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:42:11.9: SelectRunning: No running plans
2019-10-12 03:42:11.9: No pending plans that can be started now.
2019-10-12 03:42:11.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:42:42.4: SelectRunning: No running plans
2019-10-12 03:42:42.4: No pending plans that can be started now.
2019-10-12 03:42:42.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:43:13.0: SelectRunning: No running plans
2019-10-12 03:43:13.0: No pending plans that can be started now.
2019-10-12 03:43:13.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:43:43.8: SelectRunning: No running plans
2019-10-12 03:43:43.8: No pending plans that can be started now.
2019-10-12 03:43:43.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:44:14.4: SelectRunning: No running plans
2019-10-12 03:44:14.4: No pending plans that can be started now.
2019-10-12 03:44:14.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:44:44.9: SelectRunning: No running plans
2019-10-12 03:44:44.9: No pending plans that can be started now.
2019-10-12 03:44:44.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:45:15.9: SelectRunning: No running plans
2019-10-12 03:45:15.9: No pending plans that can be started now.
2019-10-12 03:45:15.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:45:46.4: SelectRunning: No running plans
2019-10-12 03:45:46.4: No pending plans that can be started now.
2019-10-12 03:45:46.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:46:17.0: SelectRunning: No running plans
2019-10-12 03:46:17.0: No pending plans that can be started now.
2019-10-12 03:46:17.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:46:47.6: SelectRunning: No running plans
2019-10-12 03:46:47.6: No pending plans that can be started now.
2019-10-12 03:46:47.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:47:18.2: SelectRunning: No running plans
2019-10-12 03:47:18.2: No pending plans that can be started now.
2019-10-12 03:47:18.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:47:48.8: SelectRunning: No running plans
2019-10-12 03:47:48.8: No pending plans that can be started now.
2019-10-12 03:47:48.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:48:19.4: SelectRunning: No running plans
2019-10-12 03:48:19.4: No pending plans that can be started now.
2019-10-12 03:48:19.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:48:50.0: SelectRunning: No running plans
2019-10-12 03:48:50.0: No pending plans that can be started now.
2019-10-12 03:48:50.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:49:20.6: SelectRunning: No running plans
2019-10-12 03:49:20.6: No pending plans that can be started now.
2019-10-12 03:49:20.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:49:51.1: SelectRunning: No running plans
2019-10-12 03:49:51.1: No pending plans that can be started now.
2019-10-12 03:49:51.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:50:21.7: SelectRunning: No running plans
2019-10-12 03:50:21.7: No pending plans that can be started now.
2019-10-12 03:50:21.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:50:52.3: SelectRunning: No running plans
2019-10-12 03:50:52.4: Efficiency: Only one pending plan Test, it is the "best"
2019-10-12 03:50:52.4: Dispatcher cycle time: 0.1 sec.
2019-10-12 03:50:52.4: Acquire data for Observation Test...
2019-10-12 03:50:52.4:   (belongs to Project KH15D, Plan Test)
2019-10-12 03:50:52.4: Send Observation Test to ACP Sequencer
2019-10-12 03:50:52.4: ACP.RunScript(AcquireScheduler, ):
2019-10-12 03:50:52.4:   Script AutoFocusScheduler is loaded, and
2019-10-12 03:50:52.4:   the console is idle.
2019-10-12 03:50:52.4:   In start loop, i = 0
2019-10-12 03:50:52.4:   Console is now idle
2019-10-12 03:50:52.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-12 03:50:52.4:   Script started successfully
2019-10-12 03:51:02.4: Sequencer is now active
2019-10-12 03:52:44.2: Sequencer is no longer active
2019-10-12 03:52:44.2: Post-job status check done (stat=Failed)
2019-10-12 03:52:44.2: ACP data acquisition failed for Observation Test.
2019-10-12 03:52:44.2:   (Observation Test interrupted by acquisition process failure.)
2019-10-12 03:52:44.3: SelectRunning: No running plans
2019-10-12 03:52:44.3: No pending plans that can be started now.
2019-10-12 03:52:44.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:53:14.9: SelectRunning: No running plans
2019-10-12 03:53:14.9: No pending plans that can be started now.
2019-10-12 03:53:14.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:53:45.5: SelectRunning: No running plans
2019-10-12 03:53:45.5: No pending plans that can be started now.
2019-10-12 03:53:45.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:54:16.1: SelectRunning: No running plans
2019-10-12 03:54:16.1: No pending plans that can be started now.
2019-10-12 03:54:16.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:54:46.7: SelectRunning: No running plans
2019-10-12 03:54:46.7: No pending plans that can be started now.
2019-10-12 03:54:46.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:55:17.3: SelectRunning: No running plans
2019-10-12 03:55:17.3: No pending plans that can be started now.
2019-10-12 03:55:17.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:55:47.9: SelectRunning: No running plans
2019-10-12 03:55:47.9: No pending plans that can be started now.
2019-10-12 03:55:47.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:56:18.4: SelectRunning: No running plans
2019-10-12 03:56:18.4: No pending plans that can be started now.
2019-10-12 03:56:18.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:56:49.0: SelectRunning: No running plans
2019-10-12 03:56:49.0: No pending plans that can be started now.
2019-10-12 03:56:49.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:57:19.9: SelectRunning: No running plans
2019-10-12 03:57:19.9: Efficiency: Only one pending plan Test, it is the "best"
2019-10-12 03:57:19.9: Dispatcher cycle time: 0.1 sec.
2019-10-12 03:57:19.9: Acquire data for Observation Test...
2019-10-12 03:57:19.9:   (belongs to Project KH15D, Plan Test)
2019-10-12 03:57:19.9: Send Observation Test to ACP Sequencer
2019-10-12 03:57:19.9: ACP.RunScript(AcquireScheduler, ):
2019-10-12 03:57:19.9:   Script AcquireScheduler is loaded, and
2019-10-12 03:57:19.9:   the console is idle.
2019-10-12 03:57:19.9:   In start loop, i = 0
2019-10-12 03:57:19.9:   Console is now idle
2019-10-12 03:57:20.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2019-10-12 03:57:20.0:   Script started successfully
2019-10-12 03:57:30.0: Sequencer is now active
2019-10-12 03:58:05.4: Sequencer is no longer active
2019-10-12 03:58:05.4: Post-job status check done (stat=Failed)
2019-10-12 03:58:05.4: ACP data acquisition failed for Observation Test.
2019-10-12 03:58:05.4:   (Observation Test interrupted by acquisition process failure.)
2019-10-12 03:58:05.6: SelectRunning: No running plans
2019-10-12 03:58:05.6: No pending plans that can be started now.
2019-10-12 03:58:05.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:58:36.2: SelectRunning: No running plans
2019-10-12 03:58:36.2: No pending plans that can be started now.
2019-10-12 03:58:36.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:59:06.8: SelectRunning: No running plans
2019-10-12 03:59:06.8: No pending plans that can be started now.
2019-10-12 03:59:06.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 03:59:37.4: SelectRunning: No running plans
2019-10-12 03:59:37.4: No pending plans that can be started now.
2019-10-12 03:59:37.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:00:08.0: SelectRunning: No running plans
2019-10-12 04:00:08.0: No pending plans that can be started now.
2019-10-12 04:00:08.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:00:38.6: SelectRunning: No running plans
2019-10-12 04:00:38.6: No pending plans that can be started now.
2019-10-12 04:00:38.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:01:09.4: SelectRunning: No running plans
2019-10-12 04:01:09.4: No pending plans that can be started now.
2019-10-12 04:01:09.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:01:40.0: SelectRunning: No running plans
2019-10-12 04:01:40.0: No pending plans that can be started now.
2019-10-12 04:01:40.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:02:10.6: SelectRunning: No running plans
2019-10-12 04:02:10.6: No pending plans that can be started now.
2019-10-12 04:02:10.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:02:41.2: SelectRunning: No running plans
2019-10-12 04:02:41.2: No pending plans that can be started now.
2019-10-12 04:02:41.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:03:11.8: SelectRunning: No running plans
2019-10-12 04:03:11.8: No pending plans that can be started now.
2019-10-12 04:03:11.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:03:42.4: SelectRunning: No running plans
2019-10-12 04:03:42.4: No pending plans that can be started now.
2019-10-12 04:03:42.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:04:13.0: SelectRunning: No running plans
2019-10-12 04:04:13.0: No pending plans that can be started now.
2019-10-12 04:04:13.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:04:43.5: SelectRunning: No running plans
2019-10-12 04:04:43.5: No pending plans that can be started now.
2019-10-12 04:04:43.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:05:14.1: SelectRunning: No running plans
2019-10-12 04:05:14.1: No pending plans that can be started now.
2019-10-12 04:05:14.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:05:44.7: SelectRunning: No running plans
2019-10-12 04:05:44.7: No pending plans that can be started now.
2019-10-12 04:05:44.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:06:15.3: SelectRunning: No running plans
2019-10-12 04:06:15.3: No pending plans that can be started now.
2019-10-12 04:06:15.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:06:45.9: SelectRunning: No running plans
2019-10-12 04:06:45.9: No pending plans that can be started now.
2019-10-12 04:06:45.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:07:16.4: SelectRunning: No running plans
2019-10-12 04:07:16.4: No pending plans that can be started now.
2019-10-12 04:07:16.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:07:47.0: SelectRunning: No running plans
2019-10-12 04:07:47.0: No pending plans that can be started now.
2019-10-12 04:07:47.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:08:17.6: SelectRunning: No running plans
2019-10-12 04:08:17.6: No pending plans that can be started now.
2019-10-12 04:08:17.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:08:48.2: SelectRunning: No running plans
2019-10-12 04:08:48.2: No pending plans that can be started now.
2019-10-12 04:08:48.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:09:18.8: SelectRunning: No running plans
2019-10-12 04:09:18.8: No pending plans that can be started now.
2019-10-12 04:09:18.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:09:49.5: SelectRunning: No running plans
2019-10-12 04:09:49.5: No pending plans that can be started now.
2019-10-12 04:09:49.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:10:20.1: SelectRunning: No running plans
2019-10-12 04:10:20.1: No pending plans that can be started now.
2019-10-12 04:10:20.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:10:50.7: SelectRunning: No running plans
2019-10-12 04:10:50.7: No pending plans that can be started now.
2019-10-12 04:10:50.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:11:21.6: SelectRunning: No running plans
2019-10-12 04:11:21.6: No pending plans that can be started now.
2019-10-12 04:11:21.6: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:11:52.2: SelectRunning: No running plans
2019-10-12 04:11:52.2: No pending plans that can be started now.
2019-10-12 04:11:52.2: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:12:22.8: SelectRunning: No running plans
2019-10-12 04:12:22.8: No pending plans that can be started now.
2019-10-12 04:12:22.8: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:12:53.4: SelectRunning: No running plans
2019-10-12 04:12:53.4: No pending plans that can be started now.
2019-10-12 04:12:53.4: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:13:24.0: SelectRunning: No running plans
2019-10-12 04:13:24.0: No pending plans that can be started now.
2019-10-12 04:13:24.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:13:54.5: SelectRunning: No running plans
2019-10-12 04:13:54.5: No pending plans that can be started now.
2019-10-12 04:13:54.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:14:25.1: SelectRunning: No running plans
2019-10-12 04:14:25.1: No pending plans that can be started now.
2019-10-12 04:14:25.1: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:14:55.7: SelectRunning: No running plans
2019-10-12 04:14:55.7: No pending plans that can be started now.
2019-10-12 04:14:55.7: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:15:26.3: SelectRunning: No running plans
2019-10-12 04:15:26.3: No pending plans that can be started now.
2019-10-12 04:15:26.3: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:15:56.9: SelectRunning: No running plans
2019-10-12 04:15:56.9: No pending plans that can be started now.
2019-10-12 04:15:56.9: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:16:27.5: SelectRunning: No running plans
2019-10-12 04:16:27.5: No pending plans that can be started now.
2019-10-12 04:16:27.5: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:16:58.0: SelectRunning: No running plans
2019-10-12 04:16:58.0: No pending plans that can be started now.
2019-10-12 04:16:58.0: Dispatcher cycle time: 0.0 sec.
2019-10-12 04:17:28.6: SelectRunning: No running plans
2019-10-12 04:17:28.6: Efficiency: Only one pending plan Test, it is the "best"
2019-10-12 04:17:28.7: Dispatcher cycle time: 0.1 sec.
2019-10-12 04:17:28.7: Acquire data for Observation Test...
2019-10-12 04:17:28.7:   (belongs to Project KH15D, Plan Test)
2019-10-12 04:17:28.7: **EXCEPTION IN SCHEDULER:
2019-10-12 04:17:28.7: The telescope is not connected.
2019-10-12 04:17:28.7: 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-12 04:17:28.8: No operator intervention command tool specified
2019-10-12 04:17:28.8: Run statistics:
2019-10-12 04:17:28.8:   Observations:   4
2019-10-12 04:17:28.8:   Considered:     0
2019-10-12 04:17:28.8:   Completed:      0
2019-10-12 04:17:28.8:   Skipped:        0
2019-10-12 04:17:28.8:   Never Eligible: 4
2019-10-12 04:17:28.8:   Failed:         0
2019-10-12 04:17:28.8: Shutter-Open efficiency: 0.00%
2019-10-12 04:17:28.8: Overall Efficiency: 8.59%
2019-10-12 04:17:28.8: Release ACP sequencer
Log closed at Sat, Oct 12 2019 04:17:30 UTC (actual time)