Log opened at Mon, Apr 20 2020 02:44:25 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2020-04-20 02:44:25.8: ++ Observatory Startup ++
2020-04-20 02:44:25.8: Start ACP Sequencer's StartupObs script
2020-04-20 02:44:25.9: ACP.RunScript(StartupObs, ):
2020-04-20 02:44:25.9:   No script is loaded. ACP is definitely idle
2020-04-20 02:44:25.9:   In start loop, i = 0
2020-04-20 02:44:25.9:   Console is now idle
2020-04-20 02:44:26.7:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2020-04-20 02:44:26.7:   Script started successfully
2020-04-20 02:44:36.8: Sequencer is now active
2020-04-20 02:44:48.5: Sequencer is no longer active
2020-04-20 02:44:48.5: Open the observatory dome/roof
2020-04-20 02:45:49.2: Observatory startup succeeded, and dome opened successfully
2020-04-20 02:45:49.2: ++ Auto Focus ++
2020-04-20 02:45:49.2: Doing initial autofocus.
2020-04-20 02:45:49.2: Start special ACP AutoFocus script for scheduler
2020-04-20 02:45:49.2: ACP.RunScript(AutoFocusScheduler, ):
2020-04-20 02:45:49.2:   Script StartupObs is loaded, and
2020-04-20 02:45:49.2:   the console is idle.
2020-04-20 02:45:49.2:   In start loop, i = 0
2020-04-20 02:45:49.2:   Console is now idle
2020-04-20 02:45:49.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-04-20 02:45:49.4:   Script started successfully
2020-04-20 02:45:59.4: Sequencer is now active
2020-04-20 02:50:17.2: Sequencer is no longer active
2020-04-20 02:50:17.2: Next periodic autofocus ASAP after 3600sec. 20-Apr-2020 03:50:03 UTC
2020-04-20 02:50:17.5: SelectRunning: No running plans
2020-04-20 02:50:17.5: STRICT VETO:
2020-04-20 02:50:17.5: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2020-04-20 02:50:17.5:     AirMass estimates ready after 20-Apr-2020 19:48:47 UTC
2020-04-20 02:50:17.5: STRICT VETO:
2020-04-20 02:50:17.5: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2020-04-20 02:50:17.5:     AirMass estimates ready after 20-Apr-2020 19:48:47 UTC
2020-04-20 02:50:17.5: Obs Test in Plan Test vetoed for sequencer hard limit.
2020-04-20 02:50:17.5:  (deferred for 65 min, until 20-Apr-2020 03:55:17 UTC
2020-04-20 02:50:17.5: No pending plans that can be started now.
2020-04-20 02:50:17.5: Dispatcher cycle time: 0.3 sec.
2020-04-20 02:50:48.1: SelectRunning: No running plans
2020-04-20 02:50:48.1: No pending plans that can be started now.
2020-04-20 02:50:48.1: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:51:18.7: SelectRunning: No running plans
2020-04-20 02:51:18.7: No pending plans that can be started now.
2020-04-20 02:51:18.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:51:49.3: SelectRunning: No running plans
2020-04-20 02:51:49.3: No pending plans that can be started now.
2020-04-20 02:51:49.3: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:52:19.9: SelectRunning: No running plans
2020-04-20 02:52:19.9: No pending plans that can be started now.
2020-04-20 02:52:19.9: Dispatcher cycle time: 0.1 sec.
2020-04-20 02:52:50.5: SelectRunning: No running plans
2020-04-20 02:52:50.5: No pending plans that can be started now.
2020-04-20 02:52:50.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:53:21.1: SelectRunning: No running plans
2020-04-20 02:53:21.1: No pending plans that can be started now.
2020-04-20 02:53:21.1: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:53:51.7: SelectRunning: No running plans
2020-04-20 02:53:51.7: No pending plans that can be started now.
2020-04-20 02:53:51.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:54:22.3: SelectRunning: No running plans
2020-04-20 02:54:22.3: No pending plans that can be started now.
2020-04-20 02:54:22.3: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:54:52.9: SelectRunning: No running plans
2020-04-20 02:54:52.9: No pending plans that can be started now.
2020-04-20 02:54:52.9: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:55:23.5: SelectRunning: No running plans
2020-04-20 02:55:23.5: No pending plans that can be started now.
2020-04-20 02:55:23.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:55:54.1: SelectRunning: No running plans
2020-04-20 02:55:54.1: No pending plans that can be started now.
2020-04-20 02:55:54.1: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:56:24.7: SelectRunning: No running plans
2020-04-20 02:56:24.7: No pending plans that can be started now.
2020-04-20 02:56:24.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:56:55.6: SelectRunning: No running plans
2020-04-20 02:56:55.6: No pending plans that can be started now.
2020-04-20 02:56:55.6: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:57:26.2: SelectRunning: No running plans
2020-04-20 02:57:26.2: No pending plans that can be started now.
2020-04-20 02:57:26.2: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:57:56.8: SelectRunning: No running plans
2020-04-20 02:57:56.8: No pending plans that can be started now.
2020-04-20 02:57:56.8: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:58:27.7: SelectRunning: No running plans
2020-04-20 02:58:27.7: No pending plans that can be started now.
2020-04-20 02:58:27.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:58:58.3: SelectRunning: No running plans
2020-04-20 02:58:58.3: No pending plans that can be started now.
2020-04-20 02:58:58.3: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:59:28.9: SelectRunning: No running plans
2020-04-20 02:59:28.9: No pending plans that can be started now.
2020-04-20 02:59:28.9: Dispatcher cycle time: 0.0 sec.
2020-04-20 02:59:59.8: SelectRunning: No running plans
2020-04-20 02:59:59.8: No pending plans that can be started now.
2020-04-20 02:59:59.8: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:00:30.4: SelectRunning: No running plans
2020-04-20 03:00:30.4: No pending plans that can be started now.
2020-04-20 03:00:30.4: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:01:01.0: SelectRunning: No running plans
2020-04-20 03:01:01.0: No pending plans that can be started now.
2020-04-20 03:01:01.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:01:32.0: SelectRunning: No running plans
2020-04-20 03:01:32.0: No pending plans that can be started now.
2020-04-20 03:01:32.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:02:02.5: SelectRunning: No running plans
2020-04-20 03:02:02.5: No pending plans that can be started now.
2020-04-20 03:02:02.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:02:33.2: SelectRunning: No running plans
2020-04-20 03:02:33.2: No pending plans that can be started now.
2020-04-20 03:02:33.2: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:03:04.1: SelectRunning: No running plans
2020-04-20 03:03:04.1: No pending plans that can be started now.
2020-04-20 03:03:04.1: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:03:34.7: SelectRunning: No running plans
2020-04-20 03:03:34.7: No pending plans that can be started now.
2020-04-20 03:03:34.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:04:05.2: SelectRunning: No running plans
2020-04-20 03:04:05.2: No pending plans that can be started now.
2020-04-20 03:04:05.2: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:04:35.8: SelectRunning: No running plans
2020-04-20 03:04:35.8: No pending plans that can be started now.
2020-04-20 03:04:35.8: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:05:06.4: SelectRunning: No running plans
2020-04-20 03:05:06.4: No pending plans that can be started now.
2020-04-20 03:05:06.4: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:05:37.0: SelectRunning: No running plans
2020-04-20 03:05:37.0: No pending plans that can be started now.
2020-04-20 03:05:37.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:06:07.6: SelectRunning: No running plans
2020-04-20 03:06:07.6: No pending plans that can be started now.
2020-04-20 03:06:07.6: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:06:38.2: SelectRunning: No running plans
2020-04-20 03:06:38.2: No pending plans that can be started now.
2020-04-20 03:06:38.2: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:07:08.7: SelectRunning: No running plans
2020-04-20 03:07:08.7: No pending plans that can be started now.
2020-04-20 03:07:08.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:07:39.3: SelectRunning: No running plans
2020-04-20 03:07:39.3: No pending plans that can be started now.
2020-04-20 03:07:39.3: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:08:09.9: SelectRunning: No running plans
2020-04-20 03:08:09.9: No pending plans that can be started now.
2020-04-20 03:08:09.9: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:08:40.5: SelectRunning: No running plans
2020-04-20 03:08:40.5: No pending plans that can be started now.
2020-04-20 03:08:40.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:09:11.1: SelectRunning: No running plans
2020-04-20 03:09:11.1: No pending plans that can be started now.
2020-04-20 03:09:11.1: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:09:41.7: SelectRunning: No running plans
2020-04-20 03:09:41.7: No pending plans that can be started now.
2020-04-20 03:09:41.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:10:12.4: SelectRunning: No running plans
2020-04-20 03:10:12.4: No pending plans that can be started now.
2020-04-20 03:10:12.4: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:10:43.0: SelectRunning: No running plans
2020-04-20 03:10:43.0: No pending plans that can be started now.
2020-04-20 03:10:43.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:11:13.6: SelectRunning: No running plans
2020-04-20 03:11:13.6: No pending plans that can be started now.
2020-04-20 03:11:13.6: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:11:44.5: SelectRunning: No running plans
2020-04-20 03:11:44.5: No pending plans that can be started now.
2020-04-20 03:11:44.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:12:15.1: SelectRunning: No running plans
2020-04-20 03:12:15.1: No pending plans that can be started now.
2020-04-20 03:12:15.1: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:12:45.7: SelectRunning: No running plans
2020-04-20 03:12:45.7: No pending plans that can be started now.
2020-04-20 03:12:45.7: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:13:16.3: SelectRunning: No running plans
2020-04-20 03:13:16.3: No pending plans that can be started now.
2020-04-20 03:13:16.3: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:13:46.9: SelectRunning: No running plans
2020-04-20 03:13:46.9: No pending plans that can be started now.
2020-04-20 03:13:46.9: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:14:17.4: SelectRunning: No running plans
2020-04-20 03:14:17.4: No pending plans that can be started now.
2020-04-20 03:14:17.4: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:14:48.0: SelectRunning: No running plans
2020-04-20 03:14:48.0: No pending plans that can be started now.
2020-04-20 03:14:48.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:15:18.6: SelectRunning: No running plans
2020-04-20 03:15:18.6: No pending plans that can be started now.
2020-04-20 03:15:18.6: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:15:49.2: SelectRunning: No running plans
2020-04-20 03:15:49.2: No pending plans that can be started now.
2020-04-20 03:15:49.2: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:16:19.8: SelectRunning: No running plans
2020-04-20 03:16:19.8: No pending plans that can be started now.
2020-04-20 03:16:19.8: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:16:50.4: SelectRunning: No running plans
2020-04-20 03:16:50.4: No pending plans that can be started now.
2020-04-20 03:16:50.4: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:17:21.0: SelectRunning: No running plans
2020-04-20 03:17:21.0: No pending plans that can be started now.
2020-04-20 03:17:21.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:17:51.9: SelectRunning: No running plans
2020-04-20 03:17:51.9: No pending plans that can be started now.
2020-04-20 03:17:51.9: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:18:22.5: SelectRunning: No running plans
2020-04-20 03:18:22.5: No pending plans that can be started now.
2020-04-20 03:18:22.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:18:53.3: SelectRunning: No running plans
2020-04-20 03:18:53.3: STRICT VETO:
2020-04-20 03:18:53.3: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2020-04-20 03:18:53.3:     AirMass estimates ready after 20-Apr-2020 19:48:47 UTC
2020-04-20 03:18:53.3: No pending plans that can be started now.
2020-04-20 03:18:53.3: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:19:23.9: SelectRunning: No running plans
2020-04-20 03:19:23.9: No pending plans that can be started now.
2020-04-20 03:19:23.9: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:19:54.5: SelectRunning: No running plans
2020-04-20 03:19:54.5: No pending plans that can be started now.
2020-04-20 03:19:54.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:20:25.5: SelectRunning: No running plans
2020-04-20 03:20:25.5: Efficiency: Only one pending plan KH15D, it is the "best"
2020-04-20 03:20:25.5: Dispatcher cycle time: 0.1 sec.
2020-04-20 03:20:25.5: Acquire data for Observation KH15D...
2020-04-20 03:20:25.5:   (belongs to Project KH15D, Plan KH15D)
2020-04-20 03:20:25.6: Send Observation KH15D to ACP Sequencer
2020-04-20 03:20:25.6: ACP.RunScript(AcquireScheduler, ):
2020-04-20 03:20:25.6:   Script AutoFocusScheduler is loaded, and
2020-04-20 03:20:25.6:   the console is idle.
2020-04-20 03:20:25.6:   In start loop, i = 0
2020-04-20 03:20:25.6:   Console is now idle
2020-04-20 03:20:25.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-04-20 03:20:25.7:   Script started successfully
2020-04-20 03:20:35.7: Sequencer is now active
2020-04-20 03:22:40.2: Sequencer is no longer active
2020-04-20 03:22:40.2: Post-job status check done (stat=Failed)
2020-04-20 03:22:40.2: ACP data acquisition failed for Observation KH15D.
2020-04-20 03:22:40.2:   (Observation KH15D interrupted by acquisition process failure.)
2020-04-20 03:22:40.4: SelectRunning: No running plans
2020-04-20 03:22:40.4: No pending plans that can be started now.
2020-04-20 03:22:40.4: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:23:11.0: SelectRunning: No running plans
2020-04-20 03:23:11.0: No pending plans that can be started now.
2020-04-20 03:23:11.0: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:23:41.5: SelectRunning: No running plans
2020-04-20 03:23:41.5: No pending plans that can be started now.
2020-04-20 03:23:41.5: Dispatcher cycle time: 0.0 sec.
2020-04-20 03:24:11.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2020-04-20 03:41:50.0: -- Weather Unsafe --
2020-04-20 04:02:05.5: ++ Weather Safe ++
2020-04-20 12:14:35.9: -- Weather Unsafe --
2020-04-21 02:12:51.6: Requested shutdown of scheduler
2020-04-21 02:12:51.9: Run statistics:
2020-04-21 02:12:51.9:   Observations:   3
2020-04-21 02:12:51.9:   Considered:     1
2020-04-21 02:12:51.9:   Completed:      0
2020-04-21 02:12:51.9:   Skipped:        0
2020-04-21 02:12:51.9:   Never Eligible: 2
2020-04-21 02:12:51.9:   Failed:         1
2020-04-21 02:12:51.9: Shutter-Open efficiency: 0.00%
2020-04-21 02:12:51.9: Overall Efficiency: 6.65%
2020-04-21 02:12:51.9: Release ACP sequencer
Log closed at Tue, Apr 21 2020 02:12:53 UTC (actual time)