Log opened at Sun, Oct 11 2020 02:21:26 UTC (actual time)
Current log level is Debug
ACP Expert Scheduler version 8.2.3
Full license: Arne Henden
2020-10-11 02:21:26.4: ++ Observatory Startup ++
2020-10-11 02:21:26.4: Start ACP Sequencer's StartupObs script
2020-10-11 02:21:26.4: ACP.RunScript(StartupObs, ):
2020-10-11 02:21:26.4:   Script ShutdownObs is loaded, and
2020-10-11 02:21:26.4:   the console is idle.
2020-10-11 02:21:26.4:   In start loop, i = 0
2020-10-11 02:21:26.4:   Console is now idle
2020-10-11 02:21:26.6:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2020-10-11 02:21:26.6:   Script started successfully
2020-10-11 02:21:36.6: Sequencer is now active
2020-10-11 02:21:41.7: Sequencer is no longer active
2020-10-11 02:21:42.1: Observatory startup succeeded
2020-10-11 02:21:42.1: ++ Auto Focus ++
2020-10-11 02:21:42.2: Doing initial autofocus.
2020-10-11 02:21:42.2: Start special ACP AutoFocus script for scheduler
2020-10-11 02:21:42.2: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 02:21:42.2:   Script StartupObs is loaded, and
2020-10-11 02:21:42.2:   the console is RUNNING.
2020-10-11 02:21:42.2:   In start loop, i = 0
2020-10-11 02:21:42.2:   Console is busy, and foundIdle is False
2020-10-11 02:21:42.2: WARNING: Request sent to busy ACP (StartupObs)
2020-10-11 02:22:42.2:   In start loop, i = 1
2020-10-11 02:22:42.2:   Console is now idle
2020-10-11 02:22:42.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 02:22:42.3:   Script started successfully
2020-10-11 02:22:52.3: Sequencer is now active
2020-10-11 02:25:08.9: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2020-10-11 02:25:23.0: Sequencer is no longer active
2020-10-11 02:25:23.0: ** ACP script "AutoFocusScheduler" failed:
2020-10-11 02:25:23.0: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2020-10-11 02:25:23.0: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2020-10-11 02:26:03.5: ++ Observatory Startup ++
2020-10-11 02:26:03.5: Start ACP Sequencer's StartupObs script
2020-10-11 02:26:03.5: ACP.RunScript(StartupObs, ):
2020-10-11 02:26:03.5:   Script AutoFocusScheduler is loaded, and
2020-10-11 02:26:03.5:   the console is idle.
2020-10-11 02:26:03.5:   In start loop, i = 0
2020-10-11 02:26:03.5:   Console is now idle
2020-10-11 02:26:03.5:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2020-10-11 02:26:03.5:   Script started successfully
2020-10-11 02:26:13.5: Sequencer is now active
2020-10-11 02:26:18.6: Sequencer is no longer active
2020-10-11 02:26:18.6: Open the observatory dome/roof
2020-10-11 02:27:49.4: Observatory startup succeeded, and dome opened successfully
2020-10-11 02:27:49.4: ++ Auto Focus ++
2020-10-11 02:27:49.4: Doing initial autofocus.
2020-10-11 02:27:49.4: Start special ACP AutoFocus script for scheduler
2020-10-11 02:27:49.4: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 02:27:49.4:   Script StartupObs is loaded, and
2020-10-11 02:27:49.4:   the console is idle.
2020-10-11 02:27:49.4:   In start loop, i = 0
2020-10-11 02:27:49.4:   Console is now idle
2020-10-11 02:27:49.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 02:27:49.4:   Script started successfully
2020-10-11 02:27:59.4: Sequencer is now active
2020-10-11 02:31:07.9: Sequencer is no longer active
2020-10-11 02:31:07.9: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 03:30:54 UTC
2020-10-11 02:31:07.9: Plan KH15D has Monitor Mode. Time to resubmit it.
2020-10-11 02:31:07.9: Plan Test has Monitor Mode. Time to resubmit it.
2020-10-11 02:31:07.9: Plan KH15D-B has Monitor Mode. Time to resubmit it.
2020-10-11 02:31:07.9: SelectRunning: No running plans
2020-10-11 02:31:07.9: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2020-10-11 02:31:07.9:     AirMass estimates ready after 11-Oct-2020 08:24:38 UTC
2020-10-11 02:31:07.9: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2020-10-11 02:31:07.9:     AirMass estimates ready after 11-Oct-2020 08:24:38 UTC
2020-10-11 02:31:08.0: Efficiency: Only one pending plan Test, it is the "best"
2020-10-11 02:31:08.0: Dispatcher cycle time: 0.1 sec.
2020-10-11 02:31:08.0: Acquire data for Observation Test...
2020-10-11 02:31:08.0:   (belongs to Project KH15D, Plan Test)
2020-10-11 02:31:08.0: Send Observation Test to ACP Sequencer
2020-10-11 02:31:08.0: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:31:08.0:   Script AutoFocusScheduler is loaded, and
2020-10-11 02:31:08.0:   the console is idle.
2020-10-11 02:31:08.0:   In start loop, i = 0
2020-10-11 02:31:08.0:   Console is now idle
2020-10-11 02:31:08.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:31:08.1:   Script started successfully
2020-10-11 02:31:18.1: Sequencer is now active
2020-10-11 02:33:00.9: Sequencer is no longer active
2020-10-11 02:33:00.9: Post-job status check done (stat=Failed)
2020-10-11 02:33:00.9: ACP data acquisition failed for Observation Test.
2020-10-11 02:33:00.9:   (Observation Test interrupted by acquisition process failure.)
2020-10-11 02:33:01.0: SelectRunning: No running plans
2020-10-11 02:33:01.1: No pending plans that can be started now.
2020-10-11 02:33:01.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 02:33:31.4: SelectRunning: No running plans
2020-10-11 02:33:31.4: No pending plans that can be started now.
2020-10-11 02:33:31.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 02:34:01.7: SelectRunning: No running plans
2020-10-11 02:34:01.7: No pending plans that can be started now.
2020-10-11 02:34:01.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 02:34:35.1: SelectRunning: No running plans
2020-10-11 02:34:35.1: Obs M 1 in Plan M 1 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.1:     AirmassRange estimates ready after 11-Oct-2020 06:49:45 UTC
2020-10-11 02:34:35.1: Obs M 10 in Plan M 10 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.1:     AirmassRange estimates ready after 11-Oct-2020 19:18:58 UTC
2020-10-11 02:34:35.1: Obs M 100 in Plan M 100 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.1:     AirmassRange estimates ready after 11-Oct-2020 13:50:23 UTC
2020-10-11 02:34:35.2: Obs M 101 in Plan M 101 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.2:     AirmassRange estimates ready after 11-Oct-2020 14:17:48 UTC
2020-10-11 02:34:35.2: Obs M 104 in Plan M 104 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.2:     AirmassRange estimates ready after 11-Oct-2020 15:32:34 UTC
2020-10-11 02:34:35.2: Obs M 105 in Plan M 105 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.2:     AirmassRange estimates ready after 11-Oct-2020 12:23:02 UTC
2020-10-11 02:34:35.2: Obs M 106 in Plan M 106 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.2:     AirmassRange estimates ready after 11-Oct-2020 12:46:23 UTC
2020-10-11 02:34:35.3: Obs M 107 in Plan M 107 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.3:     AirmassRange estimates ready after 11-Oct-2020 19:31:13 UTC
2020-10-11 02:34:35.3: Obs M 108 in Plan M 108 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.3:     AirmassRange estimates ready after 11-Oct-2020 11:24:11 UTC
2020-10-11 02:34:35.3: Obs M 109 in Plan M 109 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.3:     AirmassRange estimates ready after 11-Oct-2020 12:14:17 UTC
2020-10-11 02:34:35.3: Obs M 12 in Plan M 12 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.3:     AirmassRange estimates ready after 11-Oct-2020 19:01:48 UTC
2020-10-11 02:34:35.4: STRICT VETO:
2020-10-11 02:34:35.4: Obs M 17 in Plan M 17 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.4:     AirmassRange estimates ready after 11-Oct-2020 21:35:44 UTC
2020-10-11 02:34:35.4: Obs M 18 in Plan M 18 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.4:     AirmassRange estimates ready after 11-Oct-2020 21:40:24 UTC
2020-10-11 02:34:35.4: Obs M 19 in Plan M 19 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.4:     AirmassRange estimates ready after 11-Oct-2020 21:49:18 UTC
2020-10-11 02:34:35.4: Obs M 20 in Plan M 20 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.4:     AirmassRange estimates ready after 11-Oct-2020 22:07:40 UTC
2020-10-11 02:34:35.5: Obs M 21 in Plan M 21 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.5:     AirmassRange estimates ready after 11-Oct-2020 22:04:36 UTC
2020-10-11 02:34:35.5: Obs M 22 in Plan M 22 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.5:     AirmassRange estimates ready after 11-Oct-2020 22:50:20 UTC
2020-10-11 02:34:35.5: Obs M 23 in Plan M 23 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.5:     AirmassRange estimates ready after 11-Oct-2020 21:29:26 UTC
2020-10-11 02:34:35.5: Obs M 24 in Plan M 24 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.5:     AirmassRange estimates ready after 11-Oct-2020 21:45:54 UTC
2020-10-11 02:34:35.5: Obs M 25 in Plan M 25 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.5:     AirmassRange estimates ready after 11-Oct-2020 22:05:44 UTC
2020-10-11 02:34:35.5: Obs M 28 in Plan M 28 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.5:     AirmassRange estimates ready after 11-Oct-2020 22:49:54 UTC
2020-10-11 02:34:35.6: Obs M 3 in Plan M 3 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.6:     AirmassRange estimates ready after 11-Oct-2020 14:43:28 UTC
2020-10-11 02:34:35.6: Obs M 34 in Plan M 34 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.6:     AirmassRange estimates ready after 11-Oct-2020 03:18:53 UTC
2020-10-11 02:34:35.6: Obs M 35 in Plan M 35 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.6:     AirmassRange estimates ready after 11-Oct-2020 07:19:21 UTC
2020-10-11 02:34:35.6: Obs M 36 in Plan M 36 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.6:     AirmassRange estimates ready after 11-Oct-2020 06:27:57 UTC
2020-10-11 02:34:35.7: Obs M 37 in Plan M 37 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 06:47:07 UTC
2020-10-11 02:34:35.7: Obs M 38 in Plan M 38 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 06:17:29 UTC
2020-10-11 02:34:35.7: Obs M 4 in Plan M 4 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 21:15:28 UTC
2020-10-11 02:34:35.7: Obs M 40 in Plan M 40 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 12:30:24 UTC
2020-10-11 02:34:35.7: Obs M 41 in Plan M 41 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 10:33:56 UTC
2020-10-11 02:34:35.7: Obs M 42 in Plan M 42 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 08:03:58 UTC
2020-10-11 02:34:35.7: Obs M 43 in Plan M 43 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 08:03:30 UTC
2020-10-11 02:34:35.7: Obs M 44 in Plan M 44 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.7:     AirmassRange estimates ready after 11-Oct-2020 09:59:06 UTC
2020-10-11 02:34:35.8: Obs M 45 in Plan M 45 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 04:58:16 UTC
2020-10-11 02:34:35.8: Obs M 46 in Plan M 46 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 10:50:57 UTC
2020-10-11 02:34:35.8: Obs M 47 in Plan M 47 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 10:44:06 UTC
2020-10-11 02:34:35.8: Obs M 48 in Plan M 48 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 10:43:13 UTC
2020-10-11 02:34:35.8: Obs M 49 in Plan M 49 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 14:16:08 UTC
2020-10-11 02:34:35.8: Obs M 5 in Plan M 5 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 17:20:51 UTC
2020-10-11 02:34:35.8: Obs M 50 in Plan M 50 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 09:42:35 UTC
2020-10-11 02:34:35.8: Obs M 51 in Plan M 51 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.8:     AirmassRange estimates ready after 11-Oct-2020 13:57:16 UTC
2020-10-11 02:34:35.9: Obs M 53 in Plan M 53 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.9:     AirmassRange estimates ready after 11-Oct-2020 14:35:03 UTC
2020-10-11 02:34:35.9: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:35.9: Obs M 54 in Plan M 54 was failed by AirmassRange constraint.
2020-10-11 02:34:35.9: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:35.9: Obs M 55 in Plan M 55 was failed by AirmassRange constraint.
2020-10-11 02:34:35.9: Obs M 58 in Plan M 58 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.9:     AirmassRange estimates ready after 11-Oct-2020 14:14:28 UTC
2020-10-11 02:34:35.9: Obs M 59 in Plan M 59 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.9:     AirmassRange estimates ready after 11-Oct-2020 14:19:10 UTC
2020-10-11 02:34:35.9: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:35.9: Obs M 6 in Plan M 6 was failed by AirmassRange constraint.
2020-10-11 02:34:35.9: Obs M 60 in Plan M 60 was vetoed by AirmassRange constraint.
2020-10-11 02:34:35.9:     AirmassRange estimates ready after 11-Oct-2020 14:21:06 UTC
2020-10-11 02:34:36.0: Obs M 61 in Plan M 61 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 14:17:47 UTC
2020-10-11 02:34:36.0: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:36.0: Obs M 62 in Plan M 62 was failed by AirmassRange constraint.
2020-10-11 02:34:36.0: Obs M 63 in Plan M 63 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 13:52:16 UTC
2020-10-11 02:34:36.0: Obs M 64 in Plan M 64 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 14:11:26 UTC
2020-10-11 02:34:36.0: Obs M 65 in Plan M 65 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 12:52:52 UTC
2020-10-11 02:34:36.0: Obs M 66 in Plan M 66 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 12:54:24 UTC
2020-10-11 02:34:36.0: Obs M 67 in Plan M 67 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 10:27:48 UTC
2020-10-11 02:34:36.0: Obs M 68 in Plan M 68 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.0:     AirmassRange estimates ready after 11-Oct-2020 17:36:32 UTC
2020-10-11 02:34:36.0: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:36.0: Obs M 69 in Plan M 69 was failed by AirmassRange constraint.
2020-10-11 02:34:36.1: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:36.1: Obs M 7 in Plan M 7 was failed by AirmassRange constraint.
2020-10-11 02:34:36.1: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:36.1: Obs M 70 in Plan M 70 was failed by AirmassRange constraint.
2020-10-11 02:34:36.4: Obs M 74 in Plan M 74 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.4:     AirmassRange estimates ready after 11-Oct-2020 03:06:02 UTC
2020-10-11 02:34:36.4: Obs M 77 in Plan M 77 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.4:     AirmassRange estimates ready after 11-Oct-2020 04:53:24 UTC
2020-10-11 02:34:36.5: Obs M 78 in Plan M 78 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.5:     AirmassRange estimates ready after 11-Oct-2020 07:56:42 UTC
2020-10-11 02:34:36.5: Obs M 79 in Plan M 79 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.5:     AirmassRange estimates ready after 11-Oct-2020 09:48:05 UTC
2020-10-11 02:34:36.5: Obs M 8 in Plan M 8 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.5:     AirmassRange estimates ready after 11-Oct-2020 22:23:19 UTC
2020-10-11 02:34:36.5: Obs M 80 in Plan M 80 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.5:     AirmassRange estimates ready after 11-Oct-2020 20:21:52 UTC
2020-10-11 02:34:36.5: Obs M 81 in Plan M 81 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.5:     AirmassRange estimates ready after 11-Oct-2020 09:40:36 UTC
2020-10-11 02:34:36.5: Obs M 82 in Plan M 82 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.5:     AirmassRange estimates ready after 11-Oct-2020 09:39:13 UTC
2020-10-11 02:34:36.6: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 02:34:36.6: Obs M 83 in Plan M 83 was failed by AirmassRange constraint.
2020-10-11 02:34:36.6: Obs M 84 in Plan M 84 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.6:     AirmassRange estimates ready after 11-Oct-2020 13:59:21 UTC
2020-10-11 02:34:36.6: Obs M 85 in Plan M 85 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.6:     AirmassRange estimates ready after 11-Oct-2020 13:47:39 UTC
2020-10-11 02:34:36.6: Obs M 86 in Plan M 86 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.6:     AirmassRange estimates ready after 11-Oct-2020 14:00:18 UTC
2020-10-11 02:34:36.6: Obs M 87 in Plan M 87 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.6:     AirmassRange estimates ready after 11-Oct-2020 14:06:11 UTC
2020-10-11 02:34:36.6: Obs M 88 in Plan M 88 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.6:     AirmassRange estimates ready after 11-Oct-2020 14:02:39 UTC
2020-10-11 02:34:36.7: Obs M 89 in Plan M 89 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.7:     AirmassRange estimates ready after 11-Oct-2020 14:10:43 UTC
2020-10-11 02:34:36.7: Obs M 9 in Plan M 9 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.7:     AirmassRange estimates ready after 11-Oct-2020 20:48:35 UTC
2020-10-11 02:34:36.7: Obs M 90 in Plan M 90 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.7:     AirmassRange estimates ready after 11-Oct-2020 14:10:21 UTC
2020-10-11 02:34:36.7: Obs M 91 in Plan M 91 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.7:     AirmassRange estimates ready after 11-Oct-2020 14:05:51 UTC
2020-10-11 02:34:36.7: Obs M 93 in Plan M 93 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.7:     AirmassRange estimates ready after 11-Oct-2020 11:59:57 UTC
2020-10-11 02:34:36.8: Obs M 94 in Plan M 94 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.8:     AirmassRange estimates ready after 11-Oct-2020 13:29:03 UTC
2020-10-11 02:34:36.8: Obs M 95 in Plan M 95 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.8:     AirmassRange estimates ready after 11-Oct-2020 12:21:22 UTC
2020-10-11 02:34:36.8: Obs M 96 in Plan M 96 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.8:     AirmassRange estimates ready after 11-Oct-2020 12:23:52 UTC
2020-10-11 02:34:36.8: Obs M 97 in Plan M 97 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.8:     AirmassRange estimates ready after 11-Oct-2020 11:28:39 UTC
2020-10-11 02:34:36.8: Obs M 98 in Plan M 98 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.8:     AirmassRange estimates ready after 11-Oct-2020 13:43:24 UTC
2020-10-11 02:34:36.8: Obs M 99 in Plan M 99 was vetoed by AirmassRange constraint.
2020-10-11 02:34:36.8:     AirmassRange estimates ready after 11-Oct-2020 13:49:30 UTC
2020-10-11 02:34:36.8: Efficiency: Selecting from 25 pending plans:
2020-10-11 02:34:37.5:   Selected Plan M 30 (TotEff=1.93)
2020-10-11 02:34:37.5: Dispatcher cycle time: 5.5 sec.
2020-10-11 02:34:37.5: Acquire data for Observation M 30...
2020-10-11 02:34:37.5:   (belongs to Project Messier, Plan M 30)
2020-10-11 02:34:37.6: Send Observation M 30 to ACP Sequencer
2020-10-11 02:34:37.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:34:37.6:   Script AcquireScheduler is loaded, and
2020-10-11 02:34:37.6:   the console is idle.
2020-10-11 02:34:37.6:   In start loop, i = 0
2020-10-11 02:34:37.6:   Console is now idle
2020-10-11 02:34:37.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:34:37.6:   Script started successfully
2020-10-11 02:34:47.6: Sequencer is now active
2020-10-11 02:37:20.1: Sequencer is no longer active
2020-10-11 02:37:20.1: Post-job status check done (stat=Completed)
2020-10-11 02:37:20.1: Acquisition time: 162.5658307 sec.
2020-10-11 02:37:20.1: Data for Observation M 30 of Plan M 30 acquired successfully.
2020-10-11 02:37:20.2: Plan M 30 completed successfully.
2020-10-11 02:37:20.2: Image Efficiency: 35.7%
2020-10-11 02:37:20.2: Cycle Efficiency: 96.7%
2020-10-11 02:37:20.3: SelectRunning: No running plans
2020-10-11 02:37:20.6: Efficiency: Selecting from 24 pending plans:
2020-10-11 02:37:21.1:   Selected Plan M 72 (TotEff=2.42)
2020-10-11 02:37:21.1: Dispatcher cycle time: 0.8 sec.
2020-10-11 02:37:21.1: Acquire data for Observation M 72...
2020-10-11 02:37:21.1:   (belongs to Project Messier, Plan M 72)
2020-10-11 02:37:21.2: Send Observation M 72 to ACP Sequencer
2020-10-11 02:37:21.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:37:21.2:   Script AcquireScheduler is loaded, and
2020-10-11 02:37:21.2:   the console is idle.
2020-10-11 02:37:21.2:   In start loop, i = 0
2020-10-11 02:37:21.2:   Console is now idle
2020-10-11 02:37:21.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:37:21.2:   Script started successfully
2020-10-11 02:37:31.2: Sequencer is now active
2020-10-11 02:39:49.1: Sequencer is no longer active
2020-10-11 02:39:49.1: Post-job status check done (stat=Completed)
2020-10-11 02:39:49.2: Acquisition time: 147.9756755 sec.
2020-10-11 02:39:49.2: Data for Observation M 72 of Plan M 72 acquired successfully.
2020-10-11 02:39:49.2: Plan M 72 completed successfully.
2020-10-11 02:39:49.2: Image Efficiency: 40.3%
2020-10-11 02:39:49.2: Cycle Efficiency: 99.4%
2020-10-11 02:39:49.3: SelectRunning: No running plans
2020-10-11 02:39:49.7: Efficiency: Selecting from 23 pending plans:
2020-10-11 02:39:50.3:   Selected Plan M 73 (TotEff=2.43)
2020-10-11 02:39:50.3: Dispatcher cycle time: 1.0 sec.
2020-10-11 02:39:50.3: Acquire data for Observation M 73...
2020-10-11 02:39:50.3:   (belongs to Project Messier, Plan M 73)
2020-10-11 02:39:50.4: Send Observation M 73 to ACP Sequencer
2020-10-11 02:39:50.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:39:50.4:   Script AcquireScheduler is loaded, and
2020-10-11 02:39:50.4:   the console is idle.
2020-10-11 02:39:50.4:   In start loop, i = 0
2020-10-11 02:39:50.4:   Console is now idle
2020-10-11 02:39:50.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:39:50.4:   Script started successfully
2020-10-11 02:40:00.4: Sequencer is now active
2020-10-11 02:41:45.3: Sequencer is no longer active
2020-10-11 02:41:45.3: Post-job status check done (stat=Completed)
2020-10-11 02:41:45.3: Acquisition time: 114.9285024 sec.
2020-10-11 02:41:45.3: Data for Observation M 73 of Plan M 73 acquired successfully.
2020-10-11 02:41:45.3: Plan M 73 completed successfully.
2020-10-11 02:41:45.3: Image Efficiency: 51.7%
2020-10-11 02:41:45.3: Cycle Efficiency: 99.1%
2020-10-11 02:41:45.5: SelectRunning: No running plans
2020-10-11 02:41:45.8: Efficiency: Selecting from 22 pending plans:
2020-10-11 02:41:46.2:   Selected Plan M 2 (TotEff=2.31)
2020-10-11 02:41:46.3: Dispatcher cycle time: 0.8 sec.
2020-10-11 02:41:46.3: Acquire data for Observation M 2...
2020-10-11 02:41:46.3:   (belongs to Project Messier, Plan M 2)
2020-10-11 02:41:46.3: Send Observation M 2 to ACP Sequencer
2020-10-11 02:41:46.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:41:46.3:   Script AcquireScheduler is loaded, and
2020-10-11 02:41:46.3:   the console is idle.
2020-10-11 02:41:46.3:   In start loop, i = 0
2020-10-11 02:41:46.3:   Console is now idle
2020-10-11 02:41:46.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:41:46.3:   Script started successfully
2020-10-11 02:41:56.3: Sequencer is now active
2020-10-11 02:47:50.0: Sequencer is no longer active
2020-10-11 02:47:50.0: Post-job status check done (stat=Failed)
2020-10-11 02:47:50.0: ACP data acquisition failed for Observation M 2.
2020-10-11 02:47:50.0:   (Observation M 2 interrupted by acquisition process failure.)
2020-10-11 02:47:50.1: SelectRunning: No running plans
2020-10-11 02:47:50.2: STRICT VETO:
2020-10-11 02:47:50.2: Obs M 14 in Plan M 14 was vetoed by AirmassRange constraint.
2020-10-11 02:47:50.2:     AirmassRange estimates ready after 11-Oct-2020 19:56:25 UTC
2020-10-11 02:47:50.2: Obs M 16 in Plan M 16 was vetoed by AirmassRange constraint.
2020-10-11 02:47:50.2:     AirmassRange estimates ready after 11-Oct-2020 21:20:52 UTC
2020-10-11 02:47:50.4: Efficiency: Selecting from 19 pending plans:
2020-10-11 02:47:50.7:   Selected Plan M 15 (TotEff=2.31)
2020-10-11 02:47:50.8: Dispatcher cycle time: 0.6 sec.
2020-10-11 02:47:50.8: Acquire data for Observation M 15...
2020-10-11 02:47:50.8:   (belongs to Project Messier, Plan M 15)
2020-10-11 02:47:50.8: Send Observation M 15 to ACP Sequencer
2020-10-11 02:47:50.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:47:50.8:   Script AcquireScheduler is loaded, and
2020-10-11 02:47:50.8:   the console is idle.
2020-10-11 02:47:50.8:   In start loop, i = 0
2020-10-11 02:47:50.8:   Console is now idle
2020-10-11 02:47:50.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:47:50.8:   Script started successfully
2020-10-11 02:48:00.8: Sequencer is now active
2020-10-11 02:50:12.9: Sequencer is no longer active
2020-10-11 02:50:12.9: Post-job status check done (stat=Completed)
2020-10-11 02:50:12.9: Acquisition time: 142.054984 sec.
2020-10-11 02:50:12.9: Data for Observation M 15 of Plan M 15 acquired successfully.
2020-10-11 02:50:12.9: Plan M 15 completed successfully.
2020-10-11 02:50:12.9: Image Efficiency: 42.0%
2020-10-11 02:50:12.9: Cycle Efficiency: 99.5%
2020-10-11 02:50:13.1: SelectRunning: No running plans
2020-10-11 02:50:13.3: Efficiency: Selecting from 18 pending plans:
2020-10-11 02:50:13.7:   Selected Plan M 39 (TotEff=2.30)
2020-10-11 02:50:13.7: Dispatcher cycle time: 0.7 sec.
2020-10-11 02:50:13.7: Acquire data for Observation M 39...
2020-10-11 02:50:13.7:   (belongs to Project Messier, Plan M 39)
2020-10-11 02:50:13.8: Send Observation M 39 to ACP Sequencer
2020-10-11 02:50:13.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:50:13.8:   Script AcquireScheduler is loaded, and
2020-10-11 02:50:13.8:   the console is idle.
2020-10-11 02:50:13.8:   In start loop, i = 0
2020-10-11 02:50:13.8:   Console is now idle
2020-10-11 02:50:13.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:50:13.8:   Script started successfully
2020-10-11 02:50:23.8: Sequencer is now active
2020-10-11 02:52:48.6: Sequencer is no longer active
2020-10-11 02:52:48.6: Post-job status check done (stat=Completed)
2020-10-11 02:52:48.6: Acquisition time: 154.8931446 sec.
2020-10-11 02:52:48.6: Data for Observation M 39 of Plan M 39 acquired successfully.
2020-10-11 02:52:48.7: Plan M 39 completed successfully.
2020-10-11 02:52:48.7: Image Efficiency: 38.6%
2020-10-11 02:52:48.7: Cycle Efficiency: 99.5%
2020-10-11 02:52:48.8: SelectRunning: No running plans
2020-10-11 02:52:49.1: Efficiency: Selecting from 17 pending plans:
2020-10-11 02:52:49.4:   Selected Plan M 52 (TotEff=2.32)
2020-10-11 02:52:49.5: Dispatcher cycle time: 0.6 sec.
2020-10-11 02:52:49.5: Acquire data for Observation M 52...
2020-10-11 02:52:49.5:   (belongs to Project Messier, Plan M 52)
2020-10-11 02:52:49.5: Send Observation M 52 to ACP Sequencer
2020-10-11 02:52:49.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:52:49.5:   Script AcquireScheduler is loaded, and
2020-10-11 02:52:49.5:   the console is idle.
2020-10-11 02:52:49.5:   In start loop, i = 0
2020-10-11 02:52:49.5:   Console is now idle
2020-10-11 02:52:49.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:52:49.5:   Script started successfully
2020-10-11 02:52:59.5: Sequencer is now active
2020-10-11 02:55:25.9: Sequencer is no longer active
2020-10-11 02:55:25.9: Post-job status check done (stat=Completed)
2020-10-11 02:55:25.9: Acquisition time: 156.380298 sec.
2020-10-11 02:55:25.9: Data for Observation M 52 of Plan M 52 acquired successfully.
2020-10-11 02:55:25.9: Plan M 52 completed successfully.
2020-10-11 02:55:26.0: Image Efficiency: 38.2%
2020-10-11 02:55:26.0: Cycle Efficiency: 99.5%
2020-10-11 02:55:26.1: SelectRunning: No running plans
2020-10-11 02:55:26.3: Efficiency: Selecting from 16 pending plans:
2020-10-11 02:55:26.6:   Selected Plan M 103 (TotEff=2.33)
2020-10-11 02:55:26.7: Dispatcher cycle time: 0.6 sec.
2020-10-11 02:55:26.7: Acquire data for Observation M 103...
2020-10-11 02:55:26.7:   (belongs to Project Messier, Plan M 103)
2020-10-11 02:55:26.8: Send Observation M 103 to ACP Sequencer
2020-10-11 02:55:26.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:55:26.8:   Script AcquireScheduler is loaded, and
2020-10-11 02:55:26.8:   the console is idle.
2020-10-11 02:55:26.8:   In start loop, i = 0
2020-10-11 02:55:26.8:   Console is now idle
2020-10-11 02:55:26.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:55:26.8:   Script started successfully
2020-10-11 02:55:36.8: Sequencer is now active
2020-10-11 02:58:07.5: Sequencer is no longer active
2020-10-11 02:58:07.5: Post-job status check done (stat=Completed)
2020-10-11 02:58:07.5: Acquisition time: 160.7185226 sec.
2020-10-11 02:58:07.5: Data for Observation M 103 of Plan M 103 acquired successfully.
2020-10-11 02:58:07.5: Plan M 103 completed successfully.
2020-10-11 02:58:07.5: Image Efficiency: 37.2%
2020-10-11 02:58:07.5: Cycle Efficiency: 99.6%
2020-10-11 02:58:07.6: SelectRunning: No running plans
2020-10-11 02:58:07.9: Efficiency: Selecting from 15 pending plans:
2020-10-11 02:58:08.1:   Selected Plan M 76 (TotEff=2.35)
2020-10-11 02:58:08.2: Dispatcher cycle time: 0.6 sec.
2020-10-11 02:58:08.2: Acquire data for Observation M 76...
2020-10-11 02:58:08.2:   (belongs to Project Messier, Plan M 76)
2020-10-11 02:58:08.2: Send Observation M 76 to ACP Sequencer
2020-10-11 02:58:08.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 02:58:08.2:   Script AcquireScheduler is loaded, and
2020-10-11 02:58:08.2:   the console is idle.
2020-10-11 02:58:08.2:   In start loop, i = 0
2020-10-11 02:58:08.2:   Console is now idle
2020-10-11 02:58:08.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 02:58:08.3:   Script started successfully
2020-10-11 02:58:18.3: Sequencer is now active
2020-10-11 03:00:07.4: Sequencer is no longer active
2020-10-11 03:00:07.4: Post-job status check done (stat=Completed)
2020-10-11 03:00:07.4: Acquisition time: 119.1884393 sec.
2020-10-11 03:00:07.4: Data for Observation M 76 of Plan M 76 acquired successfully.
2020-10-11 03:00:07.5: Plan M 76 completed successfully.
2020-10-11 03:00:07.5: Image Efficiency: 50.1%
2020-10-11 03:00:07.5: Cycle Efficiency: 99.5%
2020-10-11 03:00:07.6: SelectRunning: No running plans
2020-10-11 03:00:07.8: Efficiency: Selecting from 14 pending plans:
2020-10-11 03:00:08.1:   Selected Plan M 32 (TotEff=2.35)
2020-10-11 03:00:08.2: Dispatcher cycle time: 0.5 sec.
2020-10-11 03:00:08.2: Acquire data for Observation M 32...
2020-10-11 03:00:08.2:   (belongs to Project Messier, Plan M 32)
2020-10-11 03:00:08.2: Send Observation M 32 to ACP Sequencer
2020-10-11 03:00:08.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:00:08.2:   Script AcquireScheduler is loaded, and
2020-10-11 03:00:08.2:   the console is idle.
2020-10-11 03:00:08.2:   In start loop, i = 0
2020-10-11 03:00:08.2:   Console is now idle
2020-10-11 03:00:08.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:00:08.2:   Script started successfully
2020-10-11 03:00:18.2: Sequencer is now active
2020-10-11 03:02:33.5: Sequencer is no longer active
2020-10-11 03:02:33.5: Post-job status check done (stat=Completed)
2020-10-11 03:02:33.5: Acquisition time: 145.3355612 sec.
2020-10-11 03:02:33.5: Data for Observation M 32 of Plan M 32 acquired successfully.
2020-10-11 03:02:33.5: Plan M 32 completed successfully.
2020-10-11 03:02:33.6: Image Efficiency: 41.1%
2020-10-11 03:02:33.6: Cycle Efficiency: 99.6%
2020-10-11 03:02:33.7: SelectRunning: No running plans
2020-10-11 03:02:33.9: Efficiency: Selecting from 13 pending plans:
2020-10-11 03:02:34.1:   Selected Plan M 31 (TotEff=2.37)
2020-10-11 03:02:34.2: Dispatcher cycle time: 0.5 sec.
2020-10-11 03:02:34.2: Acquire data for Observation M 31...
2020-10-11 03:02:34.2:   (belongs to Project Messier, Plan M 31)
2020-10-11 03:02:34.2: Send Observation M 31 to ACP Sequencer
2020-10-11 03:02:34.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:02:34.2:   Script AcquireScheduler is loaded, and
2020-10-11 03:02:34.2:   the console is idle.
2020-10-11 03:02:34.2:   In start loop, i = 0
2020-10-11 03:02:34.2:   Console is now idle
2020-10-11 03:02:34.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:02:34.2:   Script started successfully
2020-10-11 03:02:44.3: Sequencer is now active
2020-10-11 03:04:27.6: Sequencer is no longer active
2020-10-11 03:04:27.6: Post-job status check done (stat=Completed)
2020-10-11 03:04:27.6: Acquisition time: 113.4063208 sec.
2020-10-11 03:04:27.6: Data for Observation M 31 of Plan M 31 acquired successfully.
2020-10-11 03:04:27.7: Plan M 31 completed successfully.
2020-10-11 03:04:27.7: Image Efficiency: 52.6%
2020-10-11 03:04:27.7: Cycle Efficiency: 99.5%
2020-10-11 03:04:27.8: SelectRunning: No running plans
2020-10-11 03:04:28.0: Efficiency: Selecting from 12 pending plans:
2020-10-11 03:04:28.2:   Selected Plan M 110 (TotEff=2.37)
2020-10-11 03:04:28.2: Dispatcher cycle time: 0.4 sec.
2020-10-11 03:04:28.2: Acquire data for Observation M 110...
2020-10-11 03:04:28.2:   (belongs to Project Messier, Plan M 110)
2020-10-11 03:04:28.3: Send Observation M 110 to ACP Sequencer
2020-10-11 03:04:28.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:04:28.3:   Script AcquireScheduler is loaded, and
2020-10-11 03:04:28.3:   the console is idle.
2020-10-11 03:04:28.3:   In start loop, i = 0
2020-10-11 03:04:28.3:   Console is now idle
2020-10-11 03:04:28.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:04:28.3:   Script started successfully
2020-10-11 03:04:38.3: Sequencer is now active
2020-10-11 03:06:21.1: Sequencer is no longer active
2020-10-11 03:06:21.1: Post-job status check done (stat=Completed)
2020-10-11 03:06:21.1: Acquisition time: 112.8420111 sec.
2020-10-11 03:06:21.1: Data for Observation M 110 of Plan M 110 acquired successfully.
2020-10-11 03:06:21.1: Plan M 110 completed successfully.
2020-10-11 03:06:21.2: Image Efficiency: 52.9%
2020-10-11 03:06:21.2: Cycle Efficiency: 99.6%
2020-10-11 03:06:21.3: SelectRunning: No running plans
2020-10-11 03:06:21.4: Efficiency: Selecting from 12 pending plans:
2020-10-11 03:06:21.6:   Selected Plan M 33 (TotEff=2.37)
2020-10-11 03:06:21.7: Dispatcher cycle time: 0.4 sec.
2020-10-11 03:06:21.7: Acquire data for Observation M 33...
2020-10-11 03:06:21.7:   (belongs to Project Messier, Plan M 33)
2020-10-11 03:06:21.7: Send Observation M 33 to ACP Sequencer
2020-10-11 03:06:21.7: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:06:21.7:   Script AcquireScheduler is loaded, and
2020-10-11 03:06:21.7:   the console is idle.
2020-10-11 03:06:21.7:   In start loop, i = 0
2020-10-11 03:06:21.7:   Console is now idle
2020-10-11 03:06:21.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:06:21.7:   Script started successfully
2020-10-11 03:06:31.8: Sequencer is now active
2020-10-11 03:09:28.1: Sequencer is no longer active
2020-10-11 03:09:28.1: Post-job status check done (stat=Completed)
2020-10-11 03:09:28.1: Acquisition time: 186.4088538 sec.
2020-10-11 03:09:28.1: Data for Observation M 33 of Plan M 33 acquired successfully.
2020-10-11 03:09:28.2: Plan M 33 completed successfully.
2020-10-11 03:09:28.2: Image Efficiency: 32.1%
2020-10-11 03:09:28.2: Cycle Efficiency: 99.7%
2020-10-11 03:09:28.3: SelectRunning: No running plans
2020-10-11 03:09:28.4: Efficiency: Selecting from 11 pending plans:
2020-10-11 03:09:28.6:   Selected Plan M 74 (TotEff=2.38)
2020-10-11 03:09:28.7: Dispatcher cycle time: 0.4 sec.
2020-10-11 03:09:28.7: Acquire data for Observation M 74...
2020-10-11 03:09:28.7:   (belongs to Project Messier, Plan M 74)
2020-10-11 03:09:28.7: Send Observation M 74 to ACP Sequencer
2020-10-11 03:09:28.7: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:09:28.7:   Script AcquireScheduler is loaded, and
2020-10-11 03:09:28.7:   the console is idle.
2020-10-11 03:09:28.7:   In start loop, i = 0
2020-10-11 03:09:28.7:   Console is now idle
2020-10-11 03:09:28.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:09:28.7:   Script started successfully
2020-10-11 03:09:38.7: Sequencer is now active
2020-10-11 03:11:54.4: Sequencer is no longer active
2020-10-11 03:11:54.5: Post-job status check done (stat=Completed)
2020-10-11 03:11:54.5: Acquisition time: 145.7479801 sec.
2020-10-11 03:11:54.5: Data for Observation M 74 of Plan M 74 acquired successfully.
2020-10-11 03:11:54.5: Plan M 74 completed successfully.
2020-10-11 03:11:54.5: Image Efficiency: 41.0%
2020-10-11 03:11:54.5: Cycle Efficiency: 99.7%
2020-10-11 03:11:54.6: SelectRunning: No running plans
2020-10-11 03:11:54.8: Efficiency: Selecting from 10 pending plans:
2020-10-11 03:11:55.0:   Selected Plan M 13 (TotEff=2.26)
2020-10-11 03:11:55.0: Dispatcher cycle time: 0.4 sec.
2020-10-11 03:11:55.0: Acquire data for Observation M 13...
2020-10-11 03:11:55.0:   (belongs to Project Messier, Plan M 13)
2020-10-11 03:11:55.0: Send Observation M 13 to ACP Sequencer
2020-10-11 03:11:55.0: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:11:55.0:   Script AcquireScheduler is loaded, and
2020-10-11 03:11:55.0:   the console is idle.
2020-10-11 03:11:55.0:   In start loop, i = 0
2020-10-11 03:11:55.0:   Console is now idle
2020-10-11 03:11:55.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:11:55.0:   Script started successfully
2020-10-11 03:12:05.1: Sequencer is now active
2020-10-11 03:17:06.7: Sequencer is no longer active
2020-10-11 03:17:06.7: Post-job status check done (stat=Completed)
2020-10-11 03:17:06.7: Acquisition time: 311.6325081 sec.
2020-10-11 03:17:06.7: Data for Observation M 13 of Plan M 13 acquired successfully.
2020-10-11 03:17:06.7: Plan M 13 completed successfully.
2020-10-11 03:17:06.7: Image Efficiency: 19.2%
2020-10-11 03:17:06.7: Cycle Efficiency: 99.9%
2020-10-11 03:17:06.8: SelectRunning: No running plans
2020-10-11 03:17:07.0: Efficiency: Selecting from 9 pending plans:
2020-10-11 03:17:07.2:   Selected Plan M 26 (TotEff=2.68)
2020-10-11 03:17:07.2: Dispatcher cycle time: 0.4 sec.
2020-10-11 03:17:07.2: Acquire data for Observation M 26...
2020-10-11 03:17:07.2:   (belongs to Project Messier, Plan M 26)
2020-10-11 03:17:07.2: Send Observation M 26 to ACP Sequencer
2020-10-11 03:17:07.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:17:07.2:   Script AcquireScheduler is loaded, and
2020-10-11 03:17:07.2:   the console is idle.
2020-10-11 03:17:07.2:   In start loop, i = 0
2020-10-11 03:17:07.2:   Console is now idle
2020-10-11 03:17:07.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:17:07.2:   Script started successfully
2020-10-11 03:17:17.3: Sequencer is now active
2020-10-11 03:19:48.2: Sequencer is no longer active
2020-10-11 03:19:48.2: Post-job status check done (stat=Completed)
2020-10-11 03:19:48.2: Acquisition time: 160.9654772 sec.
2020-10-11 03:19:48.2: Data for Observation M 26 of Plan M 26 acquired successfully.
2020-10-11 03:19:48.2: Plan M 26 completed successfully.
2020-10-11 03:19:48.2: Image Efficiency: 37.2%
2020-10-11 03:19:48.2: Cycle Efficiency: 99.7%
2020-10-11 03:19:48.4: SelectRunning: No running plans
2020-10-11 03:19:48.5: Efficiency: Selecting from 9 pending plans:
2020-10-11 03:19:48.7:   Selected Plan M 11 (TotEff=2.70)
2020-10-11 03:19:48.7: Dispatcher cycle time: 0.4 sec.
2020-10-11 03:19:48.7: Acquire data for Observation M 11...
2020-10-11 03:19:48.7:   (belongs to Project Messier, Plan M 11)
2020-10-11 03:19:48.8: Send Observation M 11 to ACP Sequencer
2020-10-11 03:19:48.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:19:48.8:   Script AcquireScheduler is loaded, and
2020-10-11 03:19:48.8:   the console is idle.
2020-10-11 03:19:48.8:   In start loop, i = 0
2020-10-11 03:19:48.8:   Console is now idle
2020-10-11 03:19:48.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:19:48.8:   Script started successfully
2020-10-11 03:19:58.8: Sequencer is now active
2020-10-11 03:21:42.5: Sequencer is no longer active
2020-10-11 03:21:42.5: Post-job status check done (stat=Completed)
2020-10-11 03:21:42.5: Acquisition time: 113.7564016 sec.
2020-10-11 03:21:42.5: Data for Observation M 11 of Plan M 11 acquired successfully.
2020-10-11 03:21:42.6: Plan M 11 completed successfully.
2020-10-11 03:21:42.6: Image Efficiency: 52.5%
2020-10-11 03:21:42.6: Cycle Efficiency: 99.6%
2020-10-11 03:21:42.9: SelectRunning: No running plans
2020-10-11 03:21:43.0: Efficiency: Selecting from 8 pending plans:
2020-10-11 03:21:43.2:   Selected Plan M 75 (TotEff=2.70)
2020-10-11 03:21:43.2: Dispatcher cycle time: 0.3 sec.
2020-10-11 03:21:43.2: Acquire data for Observation M 75...
2020-10-11 03:21:43.3:   (belongs to Project Messier, Plan M 75)
2020-10-11 03:21:43.3: Send Observation M 75 to ACP Sequencer
2020-10-11 03:21:43.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:21:43.3:   Script AcquireScheduler is loaded, and
2020-10-11 03:21:43.3:   the console is idle.
2020-10-11 03:21:43.3:   In start loop, i = 0
2020-10-11 03:21:43.3:   Console is now idle
2020-10-11 03:21:43.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:21:43.3:   Script started successfully
2020-10-11 03:21:53.3: Sequencer is now active
2020-10-11 03:24:14.0: Sequencer is no longer active
2020-10-11 03:24:14.0: Post-job status check done (stat=Completed)
2020-10-11 03:24:14.0: Acquisition time: 150.6743937 sec.
2020-10-11 03:24:14.0: Data for Observation M 75 of Plan M 75 acquired successfully.
2020-10-11 03:24:14.0: Plan M 75 completed successfully.
2020-10-11 03:24:14.0: Image Efficiency: 39.7%
2020-10-11 03:24:14.0: Cycle Efficiency: 99.7%
2020-10-11 03:24:14.1: SelectRunning: No running plans
2020-10-11 03:24:14.2: Efficiency: Selecting from 7 pending plans:
2020-10-11 03:24:14.4:   Selected Plan M 92 (TotEff=2.63)
2020-10-11 03:24:14.4: Dispatcher cycle time: 0.3 sec.
2020-10-11 03:24:14.4: Acquire data for Observation M 92...
2020-10-11 03:24:14.4:   (belongs to Project Messier, Plan M 92)
2020-10-11 03:24:14.4: Send Observation M 92 to ACP Sequencer
2020-10-11 03:24:14.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:24:14.4:   Script AcquireScheduler is loaded, and
2020-10-11 03:24:14.4:   the console is idle.
2020-10-11 03:24:14.4:   In start loop, i = 0
2020-10-11 03:24:14.4:   Console is now idle
2020-10-11 03:24:14.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:24:14.5:   Script started successfully
2020-10-11 03:24:24.5: Sequencer is now active
2020-10-11 03:27:08.9: Sequencer is no longer active
2020-10-11 03:27:08.9: Post-job status check done (stat=Completed)
2020-10-11 03:27:08.9: Acquisition time: 174.4986662 sec.
2020-10-11 03:27:08.9: Data for Observation M 92 of Plan M 92 acquired successfully.
2020-10-11 03:27:09.0: Plan M 92 completed successfully.
2020-10-11 03:27:09.0: Image Efficiency: 34.3%
2020-10-11 03:27:09.0: Cycle Efficiency: 99.8%
2020-10-11 03:27:09.1: SelectRunning: No running plans
2020-10-11 03:27:09.2: Efficiency: Selecting from 6 pending plans:
2020-10-11 03:27:09.3:   Selected Plan M 57 (TotEff=2.56)
2020-10-11 03:27:09.3: Dispatcher cycle time: 0.2 sec.
2020-10-11 03:27:09.3: Acquire data for Observation M 57...
2020-10-11 03:27:09.3:   (belongs to Project Messier, Plan M 57)
2020-10-11 03:27:09.4: Send Observation M 57 to ACP Sequencer
2020-10-11 03:27:09.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:27:09.4:   Script AcquireScheduler is loaded, and
2020-10-11 03:27:09.4:   the console is idle.
2020-10-11 03:27:09.4:   In start loop, i = 0
2020-10-11 03:27:09.4:   Console is now idle
2020-10-11 03:27:09.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:27:09.4:   Script started successfully
2020-10-11 03:27:19.4: Sequencer is now active
2020-10-11 03:29:43.1: Sequencer is no longer active
2020-10-11 03:29:43.1: Post-job status check done (stat=Completed)
2020-10-11 03:29:43.1: Acquisition time: 153.7200837 sec.
2020-10-11 03:29:43.1: Data for Observation M 57 of Plan M 57 acquired successfully.
2020-10-11 03:29:43.1: Plan M 57 completed successfully.
2020-10-11 03:29:43.2: Image Efficiency: 39.0%
2020-10-11 03:29:43.2: Cycle Efficiency: 99.8%
2020-10-11 03:29:43.3: SelectRunning: No running plans
2020-10-11 03:29:43.4: Efficiency: Selecting from 5 pending plans:
2020-10-11 03:29:43.5:   Selected Plan M 56 (TotEff=2.55)
2020-10-11 03:29:43.5: Dispatcher cycle time: 0.2 sec.
2020-10-11 03:29:43.5: Acquire data for Observation M 56...
2020-10-11 03:29:43.5:   (belongs to Project Messier, Plan M 56)
2020-10-11 03:29:43.6: Send Observation M 56 to ACP Sequencer
2020-10-11 03:29:43.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:29:43.6:   Script AcquireScheduler is loaded, and
2020-10-11 03:29:43.6:   the console is idle.
2020-10-11 03:29:43.6:   In start loop, i = 0
2020-10-11 03:29:43.6:   Console is now idle
2020-10-11 03:29:43.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:29:43.6:   Script started successfully
2020-10-11 03:29:53.6: Sequencer is now active
2020-10-11 03:31:40.7: Sequencer is no longer active
2020-10-11 03:31:40.7: Post-job status check done (stat=Completed)
2020-10-11 03:31:40.7: Acquisition time: 117.131118 sec.
2020-10-11 03:31:40.7: Data for Observation M 56 of Plan M 56 acquired successfully.
2020-10-11 03:31:40.7: Plan M 56 completed successfully.
2020-10-11 03:31:40.7: Image Efficiency: 51.1%
2020-10-11 03:31:40.7: Cycle Efficiency: 99.7%
2020-10-11 03:31:40.9: ++ Auto Focus ++
2020-10-11 03:31:40.9: Doing periodic autofocus.
2020-10-11 03:31:40.9: Start special ACP AutoFocus script for scheduler
2020-10-11 03:31:40.9: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 03:31:40.9:   Script AcquireScheduler is loaded, and
2020-10-11 03:31:40.9:   the console is idle.
2020-10-11 03:31:40.9:   In start loop, i = 0
2020-10-11 03:31:40.9:   Console is now idle
2020-10-11 03:31:40.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 03:31:40.9:   Script started successfully
2020-10-11 03:31:50.9: Sequencer is now active
2020-10-11 03:35:03.6: Sequencer is no longer active
2020-10-11 03:35:03.6: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 04:34:44 UTC
2020-10-11 03:35:03.6: SelectRunning: No running plans
2020-10-11 03:35:03.7: Efficiency: Selecting from 4 pending plans:
2020-10-11 03:35:03.8:   Selected Plan M 71 (TotEff=2.54)
2020-10-11 03:35:03.8: Dispatcher cycle time: 0.2 sec.
2020-10-11 03:35:03.8: Acquire data for Observation M 71...
2020-10-11 03:35:03.8:   (belongs to Project Messier, Plan M 71)
2020-10-11 03:35:03.8: Send Observation M 71 to ACP Sequencer
2020-10-11 03:35:03.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:35:03.8:   Script AutoFocusScheduler is loaded, and
2020-10-11 03:35:03.8:   the console is idle.
2020-10-11 03:35:03.8:   In start loop, i = 0
2020-10-11 03:35:03.8:   Console is now idle
2020-10-11 03:35:03.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:35:03.9:   Script started successfully
2020-10-11 03:35:13.9: Sequencer is now active
2020-10-11 03:37:23.6: Sequencer is no longer active
2020-10-11 03:37:23.6: Post-job status check done (stat=Completed)
2020-10-11 03:37:23.6: Acquisition time: 139.7924723 sec.
2020-10-11 03:37:23.6: Data for Observation M 71 of Plan M 71 acquired successfully.
2020-10-11 03:37:23.6: Plan M 71 completed successfully.
2020-10-11 03:37:23.7: Image Efficiency: 42.8%
2020-10-11 03:37:23.7: Cycle Efficiency: 99.8%
2020-10-11 03:37:23.8: SelectRunning: No running plans
2020-10-11 03:37:23.8: Efficiency: Selecting from 3 pending plans:
2020-10-11 03:37:23.9:   Selected Plan M 27 (TotEff=2.53)
2020-10-11 03:37:23.9: Dispatcher cycle time: 0.1 sec.
2020-10-11 03:37:23.9: Acquire data for Observation M 27...
2020-10-11 03:37:23.9:   (belongs to Project Messier, Plan M 27)
2020-10-11 03:37:24.0: Send Observation M 27 to ACP Sequencer
2020-10-11 03:37:24.0: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:37:24.0:   Script AcquireScheduler is loaded, and
2020-10-11 03:37:24.0:   the console is idle.
2020-10-11 03:37:24.0:   In start loop, i = 0
2020-10-11 03:37:24.0:   Console is now idle
2020-10-11 03:37:24.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:37:24.0:   Script started successfully
2020-10-11 03:37:34.0: Sequencer is now active
2020-10-11 03:39:16.5: Sequencer is no longer active
2020-10-11 03:39:16.5: Post-job status check done (stat=Completed)
2020-10-11 03:39:16.5: Acquisition time: 112.5318093 sec.
2020-10-11 03:39:16.5: Data for Observation M 27 of Plan M 27 acquired successfully.
2020-10-11 03:39:16.5: Plan M 27 completed successfully.
2020-10-11 03:39:16.5: Image Efficiency: 53.2%
2020-10-11 03:39:16.5: Cycle Efficiency: 99.8%
2020-10-11 03:39:16.7: SelectRunning: No running plans
2020-10-11 03:39:16.7: Efficiency: Selecting from 2 pending plans:
2020-10-11 03:39:16.7:   Selected Plan M 29 (TotEff=2.47)
2020-10-11 03:39:16.8: Dispatcher cycle time: 0.1 sec.
2020-10-11 03:39:16.8: Acquire data for Observation M 29...
2020-10-11 03:39:16.8:   (belongs to Project Messier, Plan M 29)
2020-10-11 03:39:16.8: Send Observation M 29 to ACP Sequencer
2020-10-11 03:39:16.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:39:16.8:   Script AcquireScheduler is loaded, and
2020-10-11 03:39:16.8:   the console is idle.
2020-10-11 03:39:16.8:   In start loop, i = 0
2020-10-11 03:39:16.8:   Console is now idle
2020-10-11 03:39:16.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:39:16.8:   Script started successfully
2020-10-11 03:39:26.8: Sequencer is now active
2020-10-11 03:41:50.6: Sequencer is no longer active
2020-10-11 03:41:50.6: Post-job status check done (stat=Completed)
2020-10-11 03:41:50.6: Acquisition time: 153.7614018 sec.
2020-10-11 03:41:50.6: Data for Observation M 29 of Plan M 29 acquired successfully.
2020-10-11 03:41:50.6: Plan M 29 completed successfully.
2020-10-11 03:41:50.6: Image Efficiency: 39.0%
2020-10-11 03:41:50.6: Cycle Efficiency: 99.9%
2020-10-11 03:41:50.9: SelectRunning: No running plans
2020-10-11 03:41:51.0: Efficiency: Only one pending plan M 34, it is the "best"
2020-10-11 03:41:51.0: Dispatcher cycle time: 0.1 sec.
2020-10-11 03:41:51.0: Acquire data for Observation M 34...
2020-10-11 03:41:51.0:   (belongs to Project Messier, Plan M 34)
2020-10-11 03:41:51.1: Send Observation M 34 to ACP Sequencer
2020-10-11 03:41:51.1: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:41:51.1:   Script AcquireScheduler is loaded, and
2020-10-11 03:41:51.1:   the console is idle.
2020-10-11 03:41:51.1:   In start loop, i = 0
2020-10-11 03:41:51.1:   Console is now idle
2020-10-11 03:41:51.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:41:51.1:   Script started successfully
2020-10-11 03:42:01.1: Sequencer is now active
2020-10-11 03:45:21.9: Sequencer is no longer active
2020-10-11 03:45:21.9: Post-job status check done (stat=Completed)
2020-10-11 03:45:21.9: Acquisition time: 210.8461329 sec.
2020-10-11 03:45:21.9: Data for Observation M 34 of Plan M 34 acquired successfully.
2020-10-11 03:45:21.9: Plan M 34 completed successfully.
2020-10-11 03:45:21.9: Image Efficiency: 28.4%
2020-10-11 03:45:21.9: Cycle Efficiency: 99.9%
2020-10-11 03:45:22.1: SelectRunning: No running plans
2020-10-11 03:45:22.1: No pending plans that can be started now.
2020-10-11 03:45:22.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:45:52.4: SelectRunning: No running plans
2020-10-11 03:45:52.4: No pending plans that can be started now.
2020-10-11 03:45:52.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:46:22.7: SelectRunning: No running plans
2020-10-11 03:46:22.7: No pending plans that can be started now.
2020-10-11 03:46:22.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:46:53.0: SelectRunning: No running plans
2020-10-11 03:46:53.0: No pending plans that can be started now.
2020-10-11 03:46:53.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:47:23.3: SelectRunning: No running plans
2020-10-11 03:47:23.3: No pending plans that can be started now.
2020-10-11 03:47:23.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:47:53.6: SelectRunning: No running plans
2020-10-11 03:47:53.6: No pending plans that can be started now.
2020-10-11 03:47:53.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:48:23.9: SelectRunning: No running plans
2020-10-11 03:48:23.9: No pending plans that can be started now.
2020-10-11 03:48:23.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:48:54.3: SelectRunning: No running plans
2020-10-11 03:48:54.3: No pending plans that can be started now.
2020-10-11 03:48:54.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:49:24.7: SelectRunning: No running plans
2020-10-11 03:49:24.7: No pending plans that can be started now.
2020-10-11 03:49:24.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:49:55.3: SelectRunning: No running plans
2020-10-11 03:49:55.3: No pending plans that can be started now.
2020-10-11 03:49:55.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 03:50:01.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2020-10-11 03:51:48.2: ++ Observatory Startup ++
2020-10-11 03:51:48.2: Start ACP Sequencer's StartupObs script
2020-10-11 03:51:48.2: Starting with open dome, unsafe weather will cause operator intervention error
2020-10-11 03:51:48.2: ACP.RunScript(StartupObs, ):
2020-10-11 03:51:48.2:   Script AcquireScheduler is loaded, and
2020-10-11 03:51:48.2:   the console is idle.
2020-10-11 03:51:48.2:   In start loop, i = 0
2020-10-11 03:51:48.2:   Console is now idle
2020-10-11 03:51:48.2:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully
2020-10-11 03:51:48.2:   Script started successfully
2020-10-11 03:51:58.2: Sequencer is now active
2020-10-11 03:52:04.4: Sequencer is no longer active
2020-10-11 03:52:04.4: Observatory startup succeeded
2020-10-11 03:52:04.4: ++ Auto Focus ++
2020-10-11 03:52:04.4: Doing initial autofocus.
2020-10-11 03:52:04.4: Start special ACP AutoFocus script for scheduler
2020-10-11 03:52:04.4: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 03:52:04.4:   Script StartupObs is loaded, and
2020-10-11 03:52:04.4:   the console is idle.
2020-10-11 03:52:04.4:   In start loop, i = 0
2020-10-11 03:52:04.4:   Console is now idle
2020-10-11 03:52:04.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 03:52:04.4:   Script started successfully
2020-10-11 03:52:14.4: Sequencer is now active
2020-10-11 03:57:27.9: Sequencer is no longer active
2020-10-11 03:57:27.9: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 04:56:57 UTC
2020-10-11 03:57:31.3: SelectRunning: No running plans
2020-10-11 03:57:31.3: Obs KH15D in Plan KH15D was vetoed by AirMass constraint.
2020-10-11 03:57:31.3:     AirMass estimates ready after 11-Oct-2020 08:24:38 UTC
2020-10-11 03:57:31.3: Obs KH15D-B in Plan KH15D-B was vetoed by AirMass constraint.
2020-10-11 03:57:31.3:     AirMass estimates ready after 11-Oct-2020 08:24:38 UTC
2020-10-11 03:57:31.3: Obs M 1 in Plan M 1 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.3:     AirmassRange estimates ready after 11-Oct-2020 06:49:45 UTC
2020-10-11 03:57:31.3: Obs M 10 in Plan M 10 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.3:     AirmassRange estimates ready after 11-Oct-2020 19:18:58 UTC
2020-10-11 03:57:31.3: Obs M 100 in Plan M 100 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.3:     AirmassRange estimates ready after 11-Oct-2020 13:50:23 UTC
2020-10-11 03:57:31.4: Obs M 101 in Plan M 101 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.4:     AirmassRange estimates ready after 11-Oct-2020 14:17:48 UTC
2020-10-11 03:57:31.4: Obs M 104 in Plan M 104 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.4:     AirmassRange estimates ready after 11-Oct-2020 15:32:34 UTC
2020-10-11 03:57:31.4: Obs M 105 in Plan M 105 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.4:     AirmassRange estimates ready after 11-Oct-2020 12:23:02 UTC
2020-10-11 03:57:31.4: Obs M 106 in Plan M 106 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.4:     AirmassRange estimates ready after 11-Oct-2020 12:46:23 UTC
2020-10-11 03:57:31.4: Obs M 107 in Plan M 107 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.4:     AirmassRange estimates ready after 11-Oct-2020 19:31:13 UTC
2020-10-11 03:57:31.4: Obs M 108 in Plan M 108 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.4:     AirmassRange estimates ready after 11-Oct-2020 11:24:11 UTC
2020-10-11 03:57:31.5: Obs M 109 in Plan M 109 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 12:14:17 UTC
2020-10-11 03:57:31.5: Obs M 11 in Plan M 11 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 21:20:31 UTC
2020-10-11 03:57:31.5: Obs M 12 in Plan M 12 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 19:01:48 UTC
2020-10-11 03:57:31.5: Obs M 13 in Plan M 13 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 17:27:30 UTC
2020-10-11 03:57:31.5: Obs M 14 in Plan M 14 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 19:56:25 UTC
2020-10-11 03:57:31.5: Obs M 16 in Plan M 16 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 21:20:52 UTC
2020-10-11 03:57:31.5: Obs M 17 in Plan M 17 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.5:     AirmassRange estimates ready after 11-Oct-2020 21:35:44 UTC
2020-10-11 03:57:31.6: Obs M 18 in Plan M 18 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.6:     AirmassRange estimates ready after 11-Oct-2020 21:40:24 UTC
2020-10-11 03:57:31.6: Obs M 19 in Plan M 19 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.6:     AirmassRange estimates ready after 11-Oct-2020 21:49:18 UTC
2020-10-11 03:57:31.6: Obs M 20 in Plan M 20 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.6:     AirmassRange estimates ready after 11-Oct-2020 22:07:40 UTC
2020-10-11 03:57:31.6: Obs M 21 in Plan M 21 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.6:     AirmassRange estimates ready after 11-Oct-2020 22:04:36 UTC
2020-10-11 03:57:31.6: Obs M 22 in Plan M 22 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.6:     AirmassRange estimates ready after 11-Oct-2020 22:50:20 UTC
2020-10-11 03:57:31.6: Obs M 23 in Plan M 23 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.6:     AirmassRange estimates ready after 11-Oct-2020 21:29:26 UTC
2020-10-11 03:57:31.7: Obs M 24 in Plan M 24 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.7:     AirmassRange estimates ready after 11-Oct-2020 21:45:54 UTC
2020-10-11 03:57:31.7: Obs M 25 in Plan M 25 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.7:     AirmassRange estimates ready after 11-Oct-2020 22:05:44 UTC
2020-10-11 03:57:31.7: Obs M 26 in Plan M 26 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.7:     AirmassRange estimates ready after 11-Oct-2020 21:27:04 UTC
2020-10-11 03:57:31.7: Obs M 28 in Plan M 28 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.7:     AirmassRange estimates ready after 11-Oct-2020 22:49:54 UTC
2020-10-11 03:57:31.7: Obs M 3 in Plan M 3 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.7:     AirmassRange estimates ready after 11-Oct-2020 14:43:28 UTC
2020-10-11 03:57:31.8: Obs M 35 in Plan M 35 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.8:     AirmassRange estimates ready after 11-Oct-2020 07:19:21 UTC
2020-10-11 03:57:31.8: Obs M 36 in Plan M 36 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.8:     AirmassRange estimates ready after 11-Oct-2020 06:27:57 UTC
2020-10-11 03:57:31.8: Obs M 37 in Plan M 37 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.8:     AirmassRange estimates ready after 11-Oct-2020 06:47:07 UTC
2020-10-11 03:57:31.8: Obs M 38 in Plan M 38 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.8:     AirmassRange estimates ready after 11-Oct-2020 06:17:29 UTC
2020-10-11 03:57:31.9: Obs M 4 in Plan M 4 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 21:15:28 UTC
2020-10-11 03:57:31.9: Obs M 40 in Plan M 40 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 12:30:24 UTC
2020-10-11 03:57:31.9: Obs M 41 in Plan M 41 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 10:33:56 UTC
2020-10-11 03:57:31.9: Obs M 42 in Plan M 42 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 08:03:58 UTC
2020-10-11 03:57:31.9: Obs M 43 in Plan M 43 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 08:03:30 UTC
2020-10-11 03:57:31.9: Obs M 44 in Plan M 44 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 09:59:06 UTC
2020-10-11 03:57:31.9: Obs M 45 in Plan M 45 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 04:58:16 UTC
2020-10-11 03:57:31.9: Obs M 46 in Plan M 46 was vetoed by AirmassRange constraint.
2020-10-11 03:57:31.9:     AirmassRange estimates ready after 11-Oct-2020 10:50:57 UTC
2020-10-11 03:57:32.0: Obs M 47 in Plan M 47 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 10:44:06 UTC
2020-10-11 03:57:32.0: Obs M 48 in Plan M 48 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 10:43:13 UTC
2020-10-11 03:57:32.0: Obs M 49 in Plan M 49 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 14:16:08 UTC
2020-10-11 03:57:32.0: Obs M 5 in Plan M 5 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 17:20:51 UTC
2020-10-11 03:57:32.0: Obs M 50 in Plan M 50 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 09:42:35 UTC
2020-10-11 03:57:32.0: Obs M 51 in Plan M 51 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 13:57:16 UTC
2020-10-11 03:57:32.0: Obs M 53 in Plan M 53 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.0:     AirmassRange estimates ready after 11-Oct-2020 14:35:03 UTC
2020-10-11 03:57:32.0: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.0: Obs M 54 in Plan M 54 was failed by AirmassRange constraint.
2020-10-11 03:57:32.1: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.1: Obs M 55 in Plan M 55 was failed by AirmassRange constraint.
2020-10-11 03:57:32.1: Obs M 58 in Plan M 58 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.1:     AirmassRange estimates ready after 11-Oct-2020 14:14:28 UTC
2020-10-11 03:57:32.1: Obs M 59 in Plan M 59 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.1:     AirmassRange estimates ready after 11-Oct-2020 14:19:10 UTC
2020-10-11 03:57:32.1: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.1: Obs M 6 in Plan M 6 was failed by AirmassRange constraint.
2020-10-11 03:57:32.1: Obs M 60 in Plan M 60 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.1:     AirmassRange estimates ready after 11-Oct-2020 14:21:06 UTC
2020-10-11 03:57:32.1: Obs M 61 in Plan M 61 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.1:     AirmassRange estimates ready after 11-Oct-2020 14:17:47 UTC
2020-10-11 03:57:32.1: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.1: Obs M 62 in Plan M 62 was failed by AirmassRange constraint.
2020-10-11 03:57:32.1: Obs M 63 in Plan M 63 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.1:     AirmassRange estimates ready after 11-Oct-2020 13:52:16 UTC
2020-10-11 03:57:32.2: Obs M 64 in Plan M 64 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.2:     AirmassRange estimates ready after 11-Oct-2020 14:11:26 UTC
2020-10-11 03:57:32.2: Obs M 65 in Plan M 65 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.2:     AirmassRange estimates ready after 11-Oct-2020 12:52:52 UTC
2020-10-11 03:57:32.2: Obs M 66 in Plan M 66 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.2:     AirmassRange estimates ready after 11-Oct-2020 12:54:24 UTC
2020-10-11 03:57:32.2: Obs M 67 in Plan M 67 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.2:     AirmassRange estimates ready after 11-Oct-2020 10:27:48 UTC
2020-10-11 03:57:32.2: Obs M 68 in Plan M 68 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.2:     AirmassRange estimates ready after 11-Oct-2020 17:36:32 UTC
2020-10-11 03:57:32.2: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.2: Obs M 69 in Plan M 69 was failed by AirmassRange constraint.
2020-10-11 03:57:32.2: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.2: Obs M 7 in Plan M 7 was failed by AirmassRange constraint.
2020-10-11 03:57:32.2: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.2: Obs M 70 in Plan M 70 was failed by AirmassRange constraint.
2020-10-11 03:57:32.3: Obs M 75 in Plan M 75 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.3:     AirmassRange estimates ready after 12-Oct-2020 00:00:34 UTC
2020-10-11 03:57:32.3: Obs M 77 in Plan M 77 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.3:     AirmassRange estimates ready after 11-Oct-2020 04:53:24 UTC
2020-10-11 03:57:32.3: Obs M 78 in Plan M 78 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.3:     AirmassRange estimates ready after 11-Oct-2020 07:56:42 UTC
2020-10-11 03:57:32.3: Obs M 79 in Plan M 79 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.3:     AirmassRange estimates ready after 11-Oct-2020 09:48:05 UTC
2020-10-11 03:57:32.3: Obs M 8 in Plan M 8 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.3:     AirmassRange estimates ready after 11-Oct-2020 22:23:19 UTC
2020-10-11 03:57:32.4: Obs M 80 in Plan M 80 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.4:     AirmassRange estimates ready after 11-Oct-2020 20:21:52 UTC
2020-10-11 03:57:32.4: Obs M 81 in Plan M 81 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.4:     AirmassRange estimates ready after 11-Oct-2020 09:40:36 UTC
2020-10-11 03:57:32.4: Obs M 82 in Plan M 82 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.4:     AirmassRange estimates ready after 11-Oct-2020 09:39:13 UTC
2020-10-11 03:57:32.4: [AirmassRange] Target will never be below the maximum air mass
2020-10-11 03:57:32.4: Obs M 83 in Plan M 83 was failed by AirmassRange constraint.
2020-10-11 03:57:32.4: Obs M 84 in Plan M 84 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.4:     AirmassRange estimates ready after 11-Oct-2020 13:59:21 UTC
2020-10-11 03:57:32.4: Obs M 85 in Plan M 85 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.4:     AirmassRange estimates ready after 11-Oct-2020 13:47:39 UTC
2020-10-11 03:57:32.5: Obs M 86 in Plan M 86 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 14:00:18 UTC
2020-10-11 03:57:32.5: Obs M 87 in Plan M 87 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 14:06:11 UTC
2020-10-11 03:57:32.5: Obs M 88 in Plan M 88 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 14:02:39 UTC
2020-10-11 03:57:32.5: Obs M 89 in Plan M 89 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 14:10:43 UTC
2020-10-11 03:57:32.5: Obs M 9 in Plan M 9 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 20:48:35 UTC
2020-10-11 03:57:32.5: Obs M 90 in Plan M 90 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 14:10:21 UTC
2020-10-11 03:57:32.5: Obs M 91 in Plan M 91 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.5:     AirmassRange estimates ready after 11-Oct-2020 14:05:51 UTC
2020-10-11 03:57:32.6: Obs M 93 in Plan M 93 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 11:59:57 UTC
2020-10-11 03:57:32.6: Obs M 94 in Plan M 94 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 13:29:03 UTC
2020-10-11 03:57:32.6: Obs M 95 in Plan M 95 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 12:21:22 UTC
2020-10-11 03:57:32.6: Obs M 96 in Plan M 96 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 12:23:52 UTC
2020-10-11 03:57:32.6: Obs M 97 in Plan M 97 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 11:28:39 UTC
2020-10-11 03:57:32.6: Obs M 98 in Plan M 98 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 13:43:24 UTC
2020-10-11 03:57:32.6: Obs M 99 in Plan M 99 was vetoed by AirmassRange constraint.
2020-10-11 03:57:32.6:     AirmassRange estimates ready after 11-Oct-2020 13:49:30 UTC
2020-10-11 03:57:32.6: Efficiency: Selecting from 21 pending plans:
2020-10-11 03:57:33.1:   Selected Plan M 92 (TotEff=2.16)
2020-10-11 03:57:33.2: Dispatcher cycle time: 5.2 sec.
2020-10-11 03:57:33.2: Acquire data for Observation M 92...
2020-10-11 03:57:33.2:   (belongs to Project Messier, Plan M 92)
2020-10-11 03:57:33.2: Send Observation M 92 to ACP Sequencer
2020-10-11 03:57:33.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 03:57:33.2:   Script AutoFocusScheduler is loaded, and
2020-10-11 03:57:33.2:   the console is idle.
2020-10-11 03:57:33.2:   In start loop, i = 0
2020-10-11 03:57:33.2:   Console is now idle
2020-10-11 03:57:33.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 03:57:33.2:   Script started successfully
2020-10-11 03:57:43.2: Sequencer is now active
2020-10-11 04:05:04.7: Sequencer is no longer active
2020-10-11 04:05:04.7: Post-job status check done (stat=Failed)
2020-10-11 04:05:04.7: ACP data acquisition failed for Observation M 92.
2020-10-11 04:05:04.7:   (Observation M 92 interrupted by acquisition process failure.)
2020-10-11 04:05:04.9: SelectRunning: No running plans
2020-10-11 04:05:05.2: Efficiency: Selecting from 20 pending plans:
2020-10-11 04:05:05.5:   Selected Plan M 30 (TotEff=2.09)
2020-10-11 04:05:05.9: Dispatcher cycle time: 1.0 sec.
2020-10-11 04:05:05.9: Acquire data for Observation M 30...
2020-10-11 04:05:05.9:   (belongs to Project Messier, Plan M 30)
2020-10-11 04:05:05.9: Send Observation M 30 to ACP Sequencer
2020-10-11 04:05:05.9: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:05:05.9:   Script AcquireScheduler is loaded, and
2020-10-11 04:05:05.9:   the console is idle.
2020-10-11 04:05:05.9:   In start loop, i = 0
2020-10-11 04:05:05.9:   Console is now idle
2020-10-11 04:05:06.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:05:06.0:   Script started successfully
2020-10-11 04:05:16.0: Sequencer is now active
2020-10-11 04:13:14.5: Sequencer is no longer active
2020-10-11 04:13:14.5: Post-job status check done (stat=Failed)
2020-10-11 04:13:14.5: ACP data acquisition failed for Observation M 30.
2020-10-11 04:13:14.5:   (Observation M 30 interrupted by acquisition process failure.)
2020-10-11 04:13:14.6: SelectRunning: No running plans
2020-10-11 04:13:14.9: Efficiency: Selecting from 19 pending plans:
2020-10-11 04:13:15.3:   Selected Plan M 72 (TotEff=2.08)
2020-10-11 04:13:15.4: Dispatcher cycle time: 0.7 sec.
2020-10-11 04:13:15.4: Acquire data for Observation M 72...
2020-10-11 04:13:15.4:   (belongs to Project Messier, Plan M 72)
2020-10-11 04:13:15.4: Send Observation M 72 to ACP Sequencer
2020-10-11 04:13:15.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:13:15.4:   Script AcquireScheduler is loaded, and
2020-10-11 04:13:15.4:   the console is idle.
2020-10-11 04:13:15.4:   In start loop, i = 0
2020-10-11 04:13:15.4:   Console is now idle
2020-10-11 04:13:15.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:13:15.4:   Script started successfully
2020-10-11 04:13:25.4: Sequencer is now active
2020-10-11 04:20:20.4: Sequencer is no longer active
2020-10-11 04:20:20.4: Post-job status check done (stat=Failed)
2020-10-11 04:20:20.4: ACP data acquisition failed for Observation M 72.
2020-10-11 04:20:20.4:   (Observation M 72 interrupted by acquisition process failure.)
2020-10-11 04:20:20.6: SelectRunning: No running plans
2020-10-11 04:20:20.9: Efficiency: Selecting from 18 pending plans:
2020-10-11 04:20:21.5:   Selected Plan M 73 (TotEff=2.08)
2020-10-11 04:20:21.6: Dispatcher cycle time: 1.0 sec.
2020-10-11 04:20:21.6: Acquire data for Observation M 73...
2020-10-11 04:20:21.6:   (belongs to Project Messier, Plan M 73)
2020-10-11 04:20:21.6: Send Observation M 73 to ACP Sequencer
2020-10-11 04:20:21.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:20:21.6:   Script AcquireScheduler is loaded, and
2020-10-11 04:20:21.6:   the console is idle.
2020-10-11 04:20:21.6:   In start loop, i = 0
2020-10-11 04:20:21.6:   Console is now idle
2020-10-11 04:20:21.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:20:21.6:   Script started successfully
2020-10-11 04:20:31.6: Sequencer is now active
2020-10-11 04:26:54.4: Sequencer is no longer active
2020-10-11 04:26:54.4: Post-job status check done (stat=Failed)
2020-10-11 04:26:54.4: ACP data acquisition failed for Observation M 73.
2020-10-11 04:26:54.4:   (Observation M 73 interrupted by acquisition process failure.)
2020-10-11 04:26:54.5: SelectRunning: No running plans
2020-10-11 04:26:54.8: Efficiency: Selecting from 17 pending plans:
2020-10-11 04:26:55.1:   Selected Plan M 57 (TotEff=2.09)
2020-10-11 04:26:55.2: Dispatcher cycle time: 0.7 sec.
2020-10-11 04:26:55.2: Acquire data for Observation M 57...
2020-10-11 04:26:55.2:   (belongs to Project Messier, Plan M 57)
2020-10-11 04:26:55.5: Send Observation M 57 to ACP Sequencer
2020-10-11 04:26:55.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:26:55.5:   Script AcquireScheduler is loaded, and
2020-10-11 04:26:55.5:   the console is idle.
2020-10-11 04:26:55.5:   In start loop, i = 0
2020-10-11 04:26:55.5:   Console is now idle
2020-10-11 04:26:55.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:26:55.5:   Script started successfully
2020-10-11 04:27:05.6: Sequencer is now active
2020-10-11 04:34:13.2: Sequencer is no longer active
2020-10-11 04:34:13.2: Post-job status check done (stat=Failed)
2020-10-11 04:34:13.2: ACP data acquisition failed for Observation M 57.
2020-10-11 04:34:13.2:   (Observation M 57 interrupted by acquisition process failure.)
2020-10-11 04:34:13.3: SelectRunning: No running plans
2020-10-11 04:34:13.6: Efficiency: Selecting from 16 pending plans:
2020-10-11 04:34:13.9:   Selected Plan M 56 (TotEff=2.08)
2020-10-11 04:34:14.0: Dispatcher cycle time: 0.7 sec.
2020-10-11 04:34:14.0: Acquire data for Observation M 56...
2020-10-11 04:34:14.0:   (belongs to Project Messier, Plan M 56)
2020-10-11 04:34:14.0: Send Observation M 56 to ACP Sequencer
2020-10-11 04:34:14.0: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:34:14.0:   Script AcquireScheduler is loaded, and
2020-10-11 04:34:14.0:   the console is idle.
2020-10-11 04:34:14.0:   In start loop, i = 0
2020-10-11 04:34:14.0:   Console is now idle
2020-10-11 04:34:14.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:34:14.1:   Script started successfully
2020-10-11 04:34:24.1: Sequencer is now active
2020-10-11 04:40:48.0: Sequencer is no longer active
2020-10-11 04:40:48.0: Post-job status check done (stat=Failed)
2020-10-11 04:40:48.0: ACP data acquisition failed for Observation M 56.
2020-10-11 04:40:48.0:   (Observation M 56 interrupted by acquisition process failure.)
2020-10-11 04:40:48.1: SelectRunning: No running plans
2020-10-11 04:40:48.3: Efficiency: Selecting from 15 pending plans:
2020-10-11 04:40:48.6:   Selected Plan M 71 (TotEff=2.06)
2020-10-11 04:40:48.7: Dispatcher cycle time: 0.6 sec.
2020-10-11 04:40:48.7: Acquire data for Observation M 71...
2020-10-11 04:40:48.7:   (belongs to Project Messier, Plan M 71)
2020-10-11 04:40:48.7: Send Observation M 71 to ACP Sequencer
2020-10-11 04:40:48.7: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:40:48.7:   Script AcquireScheduler is loaded, and
2020-10-11 04:40:48.7:   the console is idle.
2020-10-11 04:40:48.7:   In start loop, i = 0
2020-10-11 04:40:48.7:   Console is now idle
2020-10-11 04:40:48.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:40:48.7:   Script started successfully
2020-10-11 04:40:58.7: Sequencer is now active
2020-10-11 04:47:45.3: Sequencer is no longer active
2020-10-11 04:47:45.3: Post-job status check done (stat=Failed)
2020-10-11 04:47:45.3: ACP data acquisition failed for Observation M 71.
2020-10-11 04:47:45.3:   (Observation M 71 interrupted by acquisition process failure.)
2020-10-11 04:47:45.8: SelectRunning: No running plans
2020-10-11 04:47:46.0: Efficiency: Selecting from 14 pending plans:
2020-10-11 04:47:46.3:   Selected Plan M 27 (TotEff=2.05)
2020-10-11 04:47:46.3: Dispatcher cycle time: 0.6 sec.
2020-10-11 04:47:46.3: Acquire data for Observation M 27...
2020-10-11 04:47:46.3:   (belongs to Project Messier, Plan M 27)
2020-10-11 04:47:46.4: Send Observation M 27 to ACP Sequencer
2020-10-11 04:47:46.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:47:46.4:   Script AcquireScheduler is loaded, and
2020-10-11 04:47:46.4:   the console is idle.
2020-10-11 04:47:46.4:   In start loop, i = 0
2020-10-11 04:47:46.4:   Console is now idle
2020-10-11 04:47:46.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:47:46.4:   Script started successfully
2020-10-11 04:47:56.4: Sequencer is now active
2020-10-11 04:54:15.5: Sequencer is no longer active
2020-10-11 04:54:15.5: Post-job status check done (stat=Failed)
2020-10-11 04:54:15.5: ACP data acquisition failed for Observation M 27.
2020-10-11 04:54:15.5:   (Observation M 27 interrupted by acquisition process failure.)
2020-10-11 04:54:15.6: SelectRunning: No running plans
2020-10-11 04:54:15.9: Efficiency: Selecting from 14 pending plans:
2020-10-11 04:54:16.1:   Selected Plan M 2 (TotEff=2.01)
2020-10-11 04:54:16.2: Dispatcher cycle time: 0.6 sec.
2020-10-11 04:54:16.2: Acquire data for Observation M 2...
2020-10-11 04:54:16.2:   (belongs to Project Messier, Plan M 2)
2020-10-11 04:54:16.2: Send Observation M 2 to ACP Sequencer
2020-10-11 04:54:16.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 04:54:16.2:   Script AcquireScheduler is loaded, and
2020-10-11 04:54:16.2:   the console is idle.
2020-10-11 04:54:16.2:   In start loop, i = 0
2020-10-11 04:54:16.2:   Console is now idle
2020-10-11 04:54:16.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 04:54:16.2:   Script started successfully
2020-10-11 04:54:26.2: Sequencer is now active
2020-10-11 05:01:43.6: Sequencer is no longer active
2020-10-11 05:01:43.6: Post-job status check done (stat=Failed)
2020-10-11 05:01:43.6: ACP data acquisition failed for Observation M 2.
2020-10-11 05:01:43.6:   (Observation M 2 interrupted by acquisition process failure.)
2020-10-11 05:01:43.7: ++ Auto Focus ++
2020-10-11 05:01:43.7: Doing periodic autofocus.
2020-10-11 05:01:43.7: Start special ACP AutoFocus script for scheduler
2020-10-11 05:01:43.7: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 05:01:43.7:   Script AcquireScheduler is loaded, and
2020-10-11 05:01:43.7:   the console is idle.
2020-10-11 05:01:43.7:   In start loop, i = 0
2020-10-11 05:01:43.7:   Console is now idle
2020-10-11 05:01:43.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 05:01:43.8:   Script started successfully
2020-10-11 05:01:53.8: Sequencer is now active
2020-10-11 05:05:13.9: Sequencer is no longer active
2020-10-11 05:05:13.9: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 06:04:55 UTC
2020-10-11 05:05:13.9: SelectRunning: No running plans
2020-10-11 05:05:14.2: Efficiency: Selecting from 14 pending plans:
2020-10-11 05:05:14.4:   Selected Plan M 15 (TotEff=2.02)
2020-10-11 05:05:14.5: Dispatcher cycle time: 0.5 sec.
2020-10-11 05:05:14.5: Acquire data for Observation M 15...
2020-10-11 05:05:14.5:   (belongs to Project Messier, Plan M 15)
2020-10-11 05:05:14.5: Send Observation M 15 to ACP Sequencer
2020-10-11 05:05:14.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:05:14.5:   Script AutoFocusScheduler is loaded, and
2020-10-11 05:05:14.5:   the console is idle.
2020-10-11 05:05:14.5:   In start loop, i = 0
2020-10-11 05:05:14.5:   Console is now idle
2020-10-11 05:05:14.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:05:14.5:   Script started successfully
2020-10-11 05:05:24.5: Sequencer is now active
2020-10-11 05:12:27.3: Sequencer is no longer active
2020-10-11 05:12:27.3: Post-job status check done (stat=Failed)
2020-10-11 05:12:27.3: ACP data acquisition failed for Observation M 15.
2020-10-11 05:12:27.3:   (Observation M 15 interrupted by acquisition process failure.)
2020-10-11 05:12:27.5: SelectRunning: No running plans
2020-10-11 05:12:27.7: Efficiency: Selecting from 13 pending plans:
2020-10-11 05:12:28.0:   Selected Plan M 29 (TotEff=2.02)
2020-10-11 05:12:28.0: Dispatcher cycle time: 0.5 sec.
2020-10-11 05:12:28.0: Acquire data for Observation M 29...
2020-10-11 05:12:28.0:   (belongs to Project Messier, Plan M 29)
2020-10-11 05:12:28.1: Send Observation M 29 to ACP Sequencer
2020-10-11 05:12:28.1: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:12:28.1:   Script AcquireScheduler is loaded, and
2020-10-11 05:12:28.1:   the console is idle.
2020-10-11 05:12:28.1:   In start loop, i = 0
2020-10-11 05:12:28.1:   Console is now idle
2020-10-11 05:12:28.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:12:28.1:   Script started successfully
2020-10-11 05:12:38.1: Sequencer is now active
2020-10-11 05:19:34.9: Sequencer is no longer active
2020-10-11 05:19:34.9: Post-job status check done (stat=Failed)
2020-10-11 05:19:34.9: ACP data acquisition failed for Observation M 29.
2020-10-11 05:19:34.9:   (Observation M 29 interrupted by acquisition process failure.)
2020-10-11 05:19:35.1: SelectRunning: No running plans
2020-10-11 05:19:35.3: Efficiency: Selecting from 12 pending plans:
2020-10-11 05:19:35.5:   Selected Plan M 39 (TotEff=2.04)
2020-10-11 05:19:35.6: Dispatcher cycle time: 0.5 sec.
2020-10-11 05:19:35.6: Acquire data for Observation M 39...
2020-10-11 05:19:35.6:   (belongs to Project Messier, Plan M 39)
2020-10-11 05:19:35.6: Send Observation M 39 to ACP Sequencer
2020-10-11 05:19:35.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:19:35.6:   Script AcquireScheduler is loaded, and
2020-10-11 05:19:35.6:   the console is idle.
2020-10-11 05:19:35.6:   In start loop, i = 0
2020-10-11 05:19:35.6:   Console is now idle
2020-10-11 05:19:35.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:19:35.6:   Script started successfully
2020-10-11 05:19:45.7: Sequencer is now active
2020-10-11 05:26:37.5: Sequencer is no longer active
2020-10-11 05:26:37.5: Post-job status check done (stat=Failed)
2020-10-11 05:26:37.5: ACP data acquisition failed for Observation M 39.
2020-10-11 05:26:37.5:   (Observation M 39 interrupted by acquisition process failure.)
2020-10-11 05:26:38.0: SelectRunning: No running plans
2020-10-11 05:26:38.1: Efficiency: Selecting from 11 pending plans:
2020-10-11 05:26:38.3:   Selected Plan M 52 (TotEff=2.05)
2020-10-11 05:26:38.4: Dispatcher cycle time: 0.4 sec.
2020-10-11 05:26:38.4: Acquire data for Observation M 52...
2020-10-11 05:26:38.4:   (belongs to Project Messier, Plan M 52)
2020-10-11 05:26:38.4: Send Observation M 52 to ACP Sequencer
2020-10-11 05:26:38.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:26:38.4:   Script AcquireScheduler is loaded, and
2020-10-11 05:26:38.4:   the console is idle.
2020-10-11 05:26:38.4:   In start loop, i = 0
2020-10-11 05:26:38.4:   Console is now idle
2020-10-11 05:26:38.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:26:38.4:   Script started successfully
2020-10-11 05:26:48.5: Sequencer is now active
2020-10-11 05:33:50.7: Sequencer is no longer active
2020-10-11 05:33:50.7: Post-job status check done (stat=Failed)
2020-10-11 05:33:50.7: ACP data acquisition failed for Observation M 52.
2020-10-11 05:33:50.7:   (Observation M 52 interrupted by acquisition process failure.)
2020-10-11 05:33:50.9: SelectRunning: No running plans
2020-10-11 05:33:51.1: Efficiency: Selecting from 10 pending plans:
2020-10-11 05:33:51.2:   Selected Plan M 103 (TotEff=1.73)
2020-10-11 05:33:51.3: Dispatcher cycle time: 0.4 sec.
2020-10-11 05:33:51.3: Acquire data for Observation M 103...
2020-10-11 05:33:51.3:   (belongs to Project Messier, Plan M 103)
2020-10-11 05:33:51.3: Send Observation M 103 to ACP Sequencer
2020-10-11 05:33:51.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:33:51.3:   Script AcquireScheduler is loaded, and
2020-10-11 05:33:51.3:   the console is idle.
2020-10-11 05:33:51.3:   In start loop, i = 0
2020-10-11 05:33:51.3:   Console is now idle
2020-10-11 05:33:51.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:33:51.4:   Script started successfully
2020-10-11 05:34:01.4: Sequencer is now active
2020-10-11 05:41:11.5: Sequencer is no longer active
2020-10-11 05:41:11.5: Post-job status check done (stat=Failed)
2020-10-11 05:41:11.5: ACP data acquisition failed for Observation M 103.
2020-10-11 05:41:11.5:   (Observation M 103 interrupted by acquisition process failure.)
2020-10-11 05:41:12.0: SelectRunning: No running plans
2020-10-11 05:41:12.2: Efficiency: Selecting from 9 pending plans:
2020-10-11 05:41:12.3:   Selected Plan M 76 (TotEff=2.07)
2020-10-11 05:41:12.4: Dispatcher cycle time: 0.4 sec.
2020-10-11 05:41:12.4: Acquire data for Observation M 76...
2020-10-11 05:41:12.4:   (belongs to Project Messier, Plan M 76)
2020-10-11 05:41:12.4: Send Observation M 76 to ACP Sequencer
2020-10-11 05:41:12.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:41:12.4:   Script AcquireScheduler is loaded, and
2020-10-11 05:41:12.4:   the console is idle.
2020-10-11 05:41:12.4:   In start loop, i = 0
2020-10-11 05:41:12.4:   Console is now idle
2020-10-11 05:41:12.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:41:12.4:   Script started successfully
2020-10-11 05:41:22.4: Sequencer is now active
2020-10-11 05:47:46.1: Sequencer is no longer active
2020-10-11 05:47:46.1: Post-job status check done (stat=Failed)
2020-10-11 05:47:46.1: ACP data acquisition failed for Observation M 76.
2020-10-11 05:47:46.1:   (Observation M 76 interrupted by acquisition process failure.)
2020-10-11 05:47:46.3: SelectRunning: No running plans
2020-10-11 05:47:46.4: Efficiency: Selecting from 8 pending plans:
2020-10-11 05:47:46.6:   Selected Plan M 34 (TotEff=2.08)
2020-10-11 05:47:46.6: Dispatcher cycle time: 0.3 sec.
2020-10-11 05:47:46.6: Acquire data for Observation M 34...
2020-10-11 05:47:46.6:   (belongs to Project Messier, Plan M 34)
2020-10-11 05:47:46.6: Send Observation M 34 to ACP Sequencer
2020-10-11 05:47:46.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:47:46.6:   Script AcquireScheduler is loaded, and
2020-10-11 05:47:46.6:   the console is idle.
2020-10-11 05:47:46.6:   In start loop, i = 0
2020-10-11 05:47:46.6:   Console is now idle
2020-10-11 05:47:46.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:47:46.6:   Script started successfully
2020-10-11 05:47:56.7: Sequencer is now active
2020-10-11 05:54:48.8: Sequencer is no longer active
2020-10-11 05:54:48.8: Post-job status check done (stat=Failed)
2020-10-11 05:54:48.8: ACP data acquisition failed for Observation M 34.
2020-10-11 05:54:48.8:   (Observation M 34 interrupted by acquisition process failure.)
2020-10-11 05:54:49.2: SelectRunning: No running plans
2020-10-11 05:54:49.3: Efficiency: Selecting from 7 pending plans:
2020-10-11 05:54:49.5:   Selected Plan M 33 (TotEff=2.09)
2020-10-11 05:54:49.5: Dispatcher cycle time: 0.3 sec.
2020-10-11 05:54:49.5: Acquire data for Observation M 33...
2020-10-11 05:54:49.5:   (belongs to Project Messier, Plan M 33)
2020-10-11 05:54:49.6: Send Observation M 33 to ACP Sequencer
2020-10-11 05:54:49.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 05:54:49.6:   Script AcquireScheduler is loaded, and
2020-10-11 05:54:49.6:   the console is idle.
2020-10-11 05:54:49.6:   In start loop, i = 0
2020-10-11 05:54:49.6:   Console is now idle
2020-10-11 05:54:49.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 05:54:49.6:   Script started successfully
2020-10-11 05:54:59.6: Sequencer is now active
2020-10-11 06:02:34.5: Sequencer is no longer active
2020-10-11 06:02:34.5: Post-job status check done (stat=Failed)
2020-10-11 06:02:34.5: ACP data acquisition failed for Observation M 33.
2020-10-11 06:02:34.5:   (Observation M 33 interrupted by acquisition process failure.)
2020-10-11 06:02:34.6: SelectRunning: No running plans
2020-10-11 06:02:34.7: Efficiency: Selecting from 6 pending plans:
2020-10-11 06:02:34.8:   Selected Plan M 31 (TotEff=2.10)
2020-10-11 06:02:34.9: Dispatcher cycle time: 0.3 sec.
2020-10-11 06:02:34.9: Acquire data for Observation M 31...
2020-10-11 06:02:34.9:   (belongs to Project Messier, Plan M 31)
2020-10-11 06:02:34.9: Send Observation M 31 to ACP Sequencer
2020-10-11 06:02:34.9: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:02:34.9:   Script AcquireScheduler is loaded, and
2020-10-11 06:02:34.9:   the console is idle.
2020-10-11 06:02:34.9:   In start loop, i = 0
2020-10-11 06:02:34.9:   Console is now idle
2020-10-11 06:02:34.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:02:34.9:   Script started successfully
2020-10-11 06:02:44.9: Sequencer is now active
2020-10-11 06:13:44.0: Sequencer is no longer active
2020-10-11 06:13:44.0: Post-job status check done (stat=Failed)
2020-10-11 06:13:44.0: ACP data acquisition failed for Observation M 31.
2020-10-11 06:13:44.0:   (Observation M 31 interrupted by acquisition process failure.)
2020-10-11 06:13:44.2: ++ Auto Focus ++
2020-10-11 06:13:44.2: Doing periodic autofocus.
2020-10-11 06:13:44.2: Start special ACP AutoFocus script for scheduler
2020-10-11 06:13:44.2: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 06:13:44.2:   Script AcquireScheduler is loaded, and
2020-10-11 06:13:44.2:   the console is idle.
2020-10-11 06:13:44.2:   In start loop, i = 0
2020-10-11 06:13:44.2:   Console is now idle
2020-10-11 06:13:44.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 06:13:44.2:   Script started successfully
2020-10-11 06:13:54.2: Sequencer is now active
2020-10-11 06:17:53.1: Sequencer is no longer active
2020-10-11 06:17:53.1: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 07:17:34 UTC
2020-10-11 06:17:53.2: SelectRunning: No running plans
2020-10-11 06:17:53.2: Efficiency: Selecting from 6 pending plans:
2020-10-11 06:17:53.3:   Selected Plan M 32 (TotEff=1.79)
2020-10-11 06:17:53.4: Dispatcher cycle time: 0.2 sec.
2020-10-11 06:17:53.4: Acquire data for Observation M 32...
2020-10-11 06:17:53.4:   (belongs to Project Messier, Plan M 32)
2020-10-11 06:17:53.4: Send Observation M 32 to ACP Sequencer
2020-10-11 06:17:53.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:17:53.4:   Script AutoFocusScheduler is loaded, and
2020-10-11 06:17:53.4:   the console is idle.
2020-10-11 06:17:53.4:   In start loop, i = 0
2020-10-11 06:17:53.4:   Console is now idle
2020-10-11 06:17:53.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:17:53.4:   Script started successfully
2020-10-11 06:18:03.5: Sequencer is now active
2020-10-11 06:25:22.5: Sequencer is no longer active
2020-10-11 06:25:22.5: Post-job status check done (stat=Failed)
2020-10-11 06:25:22.5: ACP data acquisition failed for Observation M 32.
2020-10-11 06:25:22.5:   (Observation M 32 interrupted by acquisition process failure.)
2020-10-11 06:25:22.7: SelectRunning: No running plans
2020-10-11 06:25:22.7: Efficiency: Selecting from 5 pending plans:
2020-10-11 06:25:22.8:   Selected Plan M 110 (TotEff=2.13)
2020-10-11 06:25:22.9: Dispatcher cycle time: 0.2 sec.
2020-10-11 06:25:22.9: Acquire data for Observation M 110...
2020-10-11 06:25:22.9:   (belongs to Project Messier, Plan M 110)
2020-10-11 06:25:22.9: Send Observation M 110 to ACP Sequencer
2020-10-11 06:25:22.9: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:25:22.9:   Script AcquireScheduler is loaded, and
2020-10-11 06:25:22.9:   the console is idle.
2020-10-11 06:25:22.9:   In start loop, i = 0
2020-10-11 06:25:22.9:   Console is now idle
2020-10-11 06:25:22.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:25:22.9:   Script started successfully
2020-10-11 06:25:32.9: Sequencer is now active
2020-10-11 06:31:52.8: Sequencer is no longer active
2020-10-11 06:31:52.8: Post-job status check done (stat=Failed)
2020-10-11 06:31:52.8: ACP data acquisition failed for Observation M 110.
2020-10-11 06:31:52.8:   (Observation M 110 interrupted by acquisition process failure.)
2020-10-11 06:31:52.9: SelectRunning: No running plans
2020-10-11 06:31:53.0: Efficiency: Selecting from 5 pending plans:
2020-10-11 06:31:53.1:   Selected Plan M 74 (TotEff=1.81)
2020-10-11 06:31:53.2: Dispatcher cycle time: 0.2 sec.
2020-10-11 06:31:53.2: Acquire data for Observation M 74...
2020-10-11 06:31:53.2:   (belongs to Project Messier, Plan M 74)
2020-10-11 06:31:53.2: Send Observation M 74 to ACP Sequencer
2020-10-11 06:31:53.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:31:53.2:   Script AcquireScheduler is loaded, and
2020-10-11 06:31:53.2:   the console is idle.
2020-10-11 06:31:53.2:   In start loop, i = 0
2020-10-11 06:31:53.2:   Console is now idle
2020-10-11 06:31:53.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:31:53.2:   Script started successfully
2020-10-11 06:32:03.2: Sequencer is now active
2020-10-11 06:39:00.4: Sequencer is no longer active
2020-10-11 06:39:00.4: Post-job status check done (stat=Failed)
2020-10-11 06:39:00.4: ACP data acquisition failed for Observation M 74.
2020-10-11 06:39:00.4:   (Observation M 74 interrupted by acquisition process failure.)
2020-10-11 06:39:00.6: SelectRunning: No running plans
2020-10-11 06:39:00.7: Efficiency: Selecting from 4 pending plans:
2020-10-11 06:39:00.7:   Selected Plan M 77 (TotEff=2.15)
2020-10-11 06:39:00.8: Dispatcher cycle time: 0.2 sec.
2020-10-11 06:39:00.8: Acquire data for Observation M 77...
2020-10-11 06:39:00.8:   (belongs to Project Messier, Plan M 77)
2020-10-11 06:39:00.8: Send Observation M 77 to ACP Sequencer
2020-10-11 06:39:00.8: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:39:00.8:   Script AcquireScheduler is loaded, and
2020-10-11 06:39:00.8:   the console is idle.
2020-10-11 06:39:00.8:   In start loop, i = 0
2020-10-11 06:39:00.8:   Console is now idle
2020-10-11 06:39:00.8:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:39:00.8:   Script started successfully
2020-10-11 06:39:09.1: Sequencer is now active
2020-10-11 06:44:06.2: Sequencer is no longer active
2020-10-11 06:44:06.2: Post-job status check done (stat=Failed)
2020-10-11 06:44:06.2: ACP data acquisition failed for Observation M 77.
2020-10-11 06:44:06.2:   (Observation M 77 interrupted by acquisition process failure.)
2020-10-11 06:44:06.4: SelectRunning: No running plans
2020-10-11 06:44:06.4: Efficiency: Selecting from 3 pending plans:
2020-10-11 06:44:06.5:   Selected Plan M 45 (TotEff=2.16)
2020-10-11 06:44:06.5: Dispatcher cycle time: 0.1 sec.
2020-10-11 06:44:06.5: Acquire data for Observation M 45...
2020-10-11 06:44:06.5:   (belongs to Project Messier, Plan M 45)
2020-10-11 06:44:06.5: Send Observation M 45 to ACP Sequencer
2020-10-11 06:44:06.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:44:06.5:   Script AcquireScheduler is loaded, and
2020-10-11 06:44:06.5:   the console is idle.
2020-10-11 06:44:06.5:   In start loop, i = 0
2020-10-11 06:44:06.5:   Console is now idle
2020-10-11 06:44:06.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:44:06.6:   Script started successfully
2020-10-11 06:44:16.6: Sequencer is now active
2020-10-11 06:51:27.2: Sequencer is no longer active
2020-10-11 06:51:27.2: Post-job status check done (stat=Failed)
2020-10-11 06:51:27.2: ACP data acquisition failed for Observation M 45.
2020-10-11 06:51:27.2:   (Observation M 45 interrupted by acquisition process failure.)
2020-10-11 06:51:27.3: SelectRunning: No running plans
2020-10-11 06:51:27.4: Efficiency: Selecting from 4 pending plans:
2020-10-11 06:51:27.5:   Selected Plan M 38 (TotEff=2.17)
2020-10-11 06:51:27.5: Dispatcher cycle time: 0.2 sec.
2020-10-11 06:51:27.5: Acquire data for Observation M 38...
2020-10-11 06:51:27.5:   (belongs to Project Messier, Plan M 38)
2020-10-11 06:51:27.5: Send Observation M 38 to ACP Sequencer
2020-10-11 06:51:27.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:51:27.5:   Script AcquireScheduler is loaded, and
2020-10-11 06:51:27.5:   the console is idle.
2020-10-11 06:51:27.5:   In start loop, i = 0
2020-10-11 06:51:27.5:   Console is now idle
2020-10-11 06:51:27.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:51:27.6:   Script started successfully
2020-10-11 06:51:37.6: Sequencer is now active
2020-10-11 06:58:35.1: Sequencer is no longer active
2020-10-11 06:58:35.1: Post-job status check done (stat=Failed)
2020-10-11 06:58:35.1: ACP data acquisition failed for Observation M 38.
2020-10-11 06:58:35.1:   (Observation M 38 interrupted by acquisition process failure.)
2020-10-11 06:58:35.3: SelectRunning: No running plans
2020-10-11 06:58:35.3: Efficiency: Selecting from 3 pending plans:
2020-10-11 06:58:35.4:   Selected Plan M 36 (TotEff=2.18)
2020-10-11 06:58:35.4: Dispatcher cycle time: 0.1 sec.
2020-10-11 06:58:35.4: Acquire data for Observation M 36...
2020-10-11 06:58:35.4:   (belongs to Project Messier, Plan M 36)
2020-10-11 06:58:35.4: Send Observation M 36 to ACP Sequencer
2020-10-11 06:58:35.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 06:58:35.4:   Script AcquireScheduler is loaded, and
2020-10-11 06:58:35.4:   the console is idle.
2020-10-11 06:58:35.4:   In start loop, i = 0
2020-10-11 06:58:35.4:   Console is now idle
2020-10-11 06:58:35.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 06:58:35.4:   Script started successfully
2020-10-11 06:58:45.4: Sequencer is now active
2020-10-11 07:05:06.7: Sequencer is no longer active
2020-10-11 07:05:06.7: Post-job status check done (stat=Failed)
2020-10-11 07:05:06.7: ACP data acquisition failed for Observation M 36.
2020-10-11 07:05:06.7:   (Observation M 36 interrupted by acquisition process failure.)
2020-10-11 07:05:06.8: SelectRunning: No running plans
2020-10-11 07:05:06.9: Efficiency: Selecting from 2 pending plans:
2020-10-11 07:05:06.9:   Selected Plan M 37 (TotEff=1.49)
2020-10-11 07:05:07.0: Dispatcher cycle time: 0.1 sec.
2020-10-11 07:05:07.0: Acquire data for Observation M 37...
2020-10-11 07:05:07.0:   (belongs to Project Messier, Plan M 37)
2020-10-11 07:05:07.0: Send Observation M 37 to ACP Sequencer
2020-10-11 07:05:07.0: ACP.RunScript(AcquireScheduler, ):
2020-10-11 07:05:07.0:   Script AcquireScheduler is loaded, and
2020-10-11 07:05:07.0:   the console is idle.
2020-10-11 07:05:07.0:   In start loop, i = 0
2020-10-11 07:05:07.0:   Console is now idle
2020-10-11 07:05:07.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 07:05:07.0:   Script started successfully
2020-10-11 07:05:17.0: Sequencer is now active
2020-10-11 07:11:37.8: Sequencer is no longer active
2020-10-11 07:11:37.8: Post-job status check done (stat=Failed)
2020-10-11 07:11:37.8: ACP data acquisition failed for Observation M 37.
2020-10-11 07:11:37.8:   (Observation M 37 interrupted by acquisition process failure.)
2020-10-11 07:11:38.2: SelectRunning: No running plans
2020-10-11 07:11:38.2: Efficiency: Only one pending plan M 1, it is the "best"
2020-10-11 07:11:38.3: Dispatcher cycle time: 0.1 sec.
2020-10-11 07:11:38.3: Acquire data for Observation M 1...
2020-10-11 07:11:38.3:   (belongs to Project Messier, Plan M 1)
2020-10-11 07:11:38.3: Send Observation M 1 to ACP Sequencer
2020-10-11 07:11:38.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 07:11:38.3:   Script AcquireScheduler is loaded, and
2020-10-11 07:11:38.3:   the console is idle.
2020-10-11 07:11:38.3:   In start loop, i = 0
2020-10-11 07:11:38.3:   Console is now idle
2020-10-11 07:11:38.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 07:11:38.3:   Script started successfully
2020-10-11 07:11:48.3: Sequencer is now active
2020-10-11 07:18:38.0: Sequencer is no longer active
2020-10-11 07:18:38.0: Post-job status check done (stat=Failed)
2020-10-11 07:18:38.0: ACP data acquisition failed for Observation M 1.
2020-10-11 07:18:38.0:   (Observation M 1 interrupted by acquisition process failure.)
2020-10-11 07:18:38.2: ++ Auto Focus ++
2020-10-11 07:18:38.2: Doing periodic autofocus.
2020-10-11 07:18:38.2: Start special ACP AutoFocus script for scheduler
2020-10-11 07:18:38.2: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 07:18:38.2:   Script AcquireScheduler is loaded, and
2020-10-11 07:18:38.2:   the console is idle.
2020-10-11 07:18:38.2:   In start loop, i = 0
2020-10-11 07:18:38.2:   Console is now idle
2020-10-11 07:18:38.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 07:18:38.2:   Script started successfully
2020-10-11 07:18:48.2: Sequencer is now active
2020-10-11 07:22:58.9: Sequencer is no longer active
2020-10-11 07:22:58.9: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 08:22:44 UTC
2020-10-11 07:22:58.9: SelectRunning: No running plans
2020-10-11 07:22:58.9: Efficiency: Only one pending plan M 35, it is the "best"
2020-10-11 07:22:59.3: Dispatcher cycle time: 0.4 sec.
2020-10-11 07:22:59.3: Acquire data for Observation M 35...
2020-10-11 07:22:59.3:   (belongs to Project Messier, Plan M 35)
2020-10-11 07:22:59.3: Send Observation M 35 to ACP Sequencer
2020-10-11 07:22:59.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 07:22:59.3:   Script AutoFocusScheduler is loaded, and
2020-10-11 07:22:59.3:   the console is idle.
2020-10-11 07:22:59.3:   In start loop, i = 0
2020-10-11 07:22:59.3:   Console is now idle
2020-10-11 07:22:59.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 07:22:59.4:   Script started successfully
2020-10-11 07:23:09.4: Sequencer is now active
2020-10-11 07:31:08.7: Sequencer is no longer active
2020-10-11 07:31:08.7: Post-job status check done (stat=Failed)
2020-10-11 07:31:08.7: ACP data acquisition failed for Observation M 35.
2020-10-11 07:31:08.7:   (Observation M 35 interrupted by acquisition process failure.)
2020-10-11 07:31:09.2: SelectRunning: No running plans
2020-10-11 07:31:09.2: No pending plans that can be started now.
2020-10-11 07:31:09.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:31:39.6: SelectRunning: No running plans
2020-10-11 07:31:39.6: No pending plans that can be started now.
2020-10-11 07:31:39.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:32:09.9: SelectRunning: No running plans
2020-10-11 07:32:09.9: No pending plans that can be started now.
2020-10-11 07:32:09.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:32:40.3: SelectRunning: No running plans
2020-10-11 07:32:40.4: No pending plans that can be started now.
2020-10-11 07:32:40.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:33:10.7: SelectRunning: No running plans
2020-10-11 07:33:10.8: No pending plans that can be started now.
2020-10-11 07:33:10.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:33:41.4: SelectRunning: No running plans
2020-10-11 07:33:41.4: No pending plans that can be started now.
2020-10-11 07:33:41.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:34:11.8: SelectRunning: No running plans
2020-10-11 07:34:11.8: No pending plans that can be started now.
2020-10-11 07:34:11.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:34:42.2: SelectRunning: No running plans
2020-10-11 07:34:42.2: No pending plans that can be started now.
2020-10-11 07:34:42.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:35:12.6: SelectRunning: No running plans
2020-10-11 07:35:12.6: No pending plans that can be started now.
2020-10-11 07:35:12.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:35:43.0: SelectRunning: No running plans
2020-10-11 07:35:43.0: No pending plans that can be started now.
2020-10-11 07:35:43.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:36:13.7: SelectRunning: No running plans
2020-10-11 07:36:13.7: No pending plans that can be started now.
2020-10-11 07:36:13.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:36:44.1: SelectRunning: No running plans
2020-10-11 07:36:44.1: No pending plans that can be started now.
2020-10-11 07:36:44.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:37:14.5: SelectRunning: No running plans
2020-10-11 07:37:14.5: No pending plans that can be started now.
2020-10-11 07:37:14.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:37:44.8: SelectRunning: No running plans
2020-10-11 07:37:44.8: No pending plans that can be started now.
2020-10-11 07:37:44.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:38:15.2: SelectRunning: No running plans
2020-10-11 07:38:15.2: No pending plans that can be started now.
2020-10-11 07:38:15.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:38:45.9: SelectRunning: No running plans
2020-10-11 07:38:45.9: No pending plans that can be started now.
2020-10-11 07:38:45.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:39:16.3: SelectRunning: No running plans
2020-10-11 07:39:16.3: No pending plans that can be started now.
2020-10-11 07:39:16.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:39:46.7: SelectRunning: No running plans
2020-10-11 07:39:46.7: No pending plans that can be started now.
2020-10-11 07:39:46.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:40:17.0: SelectRunning: No running plans
2020-10-11 07:40:17.0: No pending plans that can be started now.
2020-10-11 07:40:17.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:40:47.4: SelectRunning: No running plans
2020-10-11 07:40:47.4: No pending plans that can be started now.
2020-10-11 07:40:47.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:41:19.2: SelectRunning: No running plans
2020-10-11 07:41:19.2: No pending plans that can be started now.
2020-10-11 07:41:19.2: Dispatcher cycle time: 1.2 sec.
2020-10-11 07:41:49.6: SelectRunning: No running plans
2020-10-11 07:41:49.6: No pending plans that can be started now.
2020-10-11 07:41:49.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:42:20.0: SelectRunning: No running plans
2020-10-11 07:42:20.0: No pending plans that can be started now.
2020-10-11 07:42:20.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:42:50.4: SelectRunning: No running plans
2020-10-11 07:42:50.4: No pending plans that can be started now.
2020-10-11 07:42:50.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:43:20.8: SelectRunning: No running plans
2020-10-11 07:43:20.8: No pending plans that can be started now.
2020-10-11 07:43:20.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:43:51.2: SelectRunning: No running plans
2020-10-11 07:43:51.2: No pending plans that can be started now.
2020-10-11 07:43:51.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:44:21.6: SelectRunning: No running plans
2020-10-11 07:44:21.6: No pending plans that can be started now.
2020-10-11 07:44:21.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:44:52.0: SelectRunning: No running plans
2020-10-11 07:44:52.0: No pending plans that can be started now.
2020-10-11 07:44:52.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:45:22.6: SelectRunning: No running plans
2020-10-11 07:45:22.6: No pending plans that can be started now.
2020-10-11 07:45:22.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:45:53.0: SelectRunning: No running plans
2020-10-11 07:45:53.0: No pending plans that can be started now.
2020-10-11 07:45:53.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:46:23.4: SelectRunning: No running plans
2020-10-11 07:46:23.4: No pending plans that can be started now.
2020-10-11 07:46:23.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:46:53.8: SelectRunning: No running plans
2020-10-11 07:46:53.8: No pending plans that can be started now.
2020-10-11 07:46:53.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:47:24.2: SelectRunning: No running plans
2020-10-11 07:47:24.2: No pending plans that can be started now.
2020-10-11 07:47:24.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:47:54.9: SelectRunning: No running plans
2020-10-11 07:47:54.9: No pending plans that can be started now.
2020-10-11 07:47:54.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:48:25.3: SelectRunning: No running plans
2020-10-11 07:48:25.3: No pending plans that can be started now.
2020-10-11 07:48:25.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:48:55.6: SelectRunning: No running plans
2020-10-11 07:48:55.6: No pending plans that can be started now.
2020-10-11 07:48:55.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:49:25.9: SelectRunning: No running plans
2020-10-11 07:49:26.0: No pending plans that can be started now.
2020-10-11 07:49:26.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:49:56.3: SelectRunning: No running plans
2020-10-11 07:49:56.3: No pending plans that can be started now.
2020-10-11 07:49:56.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:50:27.0: SelectRunning: No running plans
2020-10-11 07:50:27.0: No pending plans that can be started now.
2020-10-11 07:50:27.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:50:57.3: SelectRunning: No running plans
2020-10-11 07:50:57.3: No pending plans that can be started now.
2020-10-11 07:50:57.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:51:27.6: SelectRunning: No running plans
2020-10-11 07:51:27.6: No pending plans that can be started now.
2020-10-11 07:51:27.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:51:58.0: SelectRunning: No running plans
2020-10-11 07:51:58.0: No pending plans that can be started now.
2020-10-11 07:51:58.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:52:28.3: SelectRunning: No running plans
2020-10-11 07:52:28.3: No pending plans that can be started now.
2020-10-11 07:52:28.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:52:58.7: SelectRunning: No running plans
2020-10-11 07:52:58.7: No pending plans that can be started now.
2020-10-11 07:52:58.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:53:29.0: SelectRunning: No running plans
2020-10-11 07:53:29.0: No pending plans that can be started now.
2020-10-11 07:53:29.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:53:59.3: SelectRunning: No running plans
2020-10-11 07:53:59.3: No pending plans that can be started now.
2020-10-11 07:53:59.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:54:29.6: SelectRunning: No running plans
2020-10-11 07:54:29.6: No pending plans that can be started now.
2020-10-11 07:54:29.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:54:59.9: SelectRunning: No running plans
2020-10-11 07:54:59.9: No pending plans that can be started now.
2020-10-11 07:54:59.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:55:30.2: SelectRunning: No running plans
2020-10-11 07:55:30.2: No pending plans that can be started now.
2020-10-11 07:55:30.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:56:00.6: SelectRunning: No running plans
2020-10-11 07:56:00.6: No pending plans that can be started now.
2020-10-11 07:56:00.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:56:31.2: SelectRunning: No running plans
2020-10-11 07:56:31.2: No pending plans that can be started now.
2020-10-11 07:56:31.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 07:57:01.5: SelectRunning: No running plans
2020-10-11 07:57:01.5: Efficiency: Only one pending plan M 78, it is the "best"
2020-10-11 07:57:01.6: Dispatcher cycle time: 0.1 sec.
2020-10-11 07:57:01.6: Acquire data for Observation M 78...
2020-10-11 07:57:01.6:   (belongs to Project Messier, Plan M 78)
2020-10-11 07:57:01.6: Send Observation M 78 to ACP Sequencer
2020-10-11 07:57:01.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 07:57:01.6:   Script AcquireScheduler is loaded, and
2020-10-11 07:57:01.6:   the console is idle.
2020-10-11 07:57:01.6:   In start loop, i = 0
2020-10-11 07:57:01.6:   Console is now idle
2020-10-11 07:57:01.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 07:57:01.6:   Script started successfully
2020-10-11 07:57:11.6: Sequencer is now active
2020-10-11 08:04:45.2: Sequencer is no longer active
2020-10-11 08:04:45.2: Post-job status check done (stat=Failed)
2020-10-11 08:04:45.3: ACP data acquisition failed for Observation M 78.
2020-10-11 08:04:45.3:   (Observation M 78 interrupted by acquisition process failure.)
2020-10-11 08:04:45.4: SelectRunning: No running plans
2020-10-11 08:04:45.5: Efficiency: Selecting from 2 pending plans:
2020-10-11 08:04:45.5:   Selected Plan M 42 (TotEff=1.61)
2020-10-11 08:04:45.5: Dispatcher cycle time: 0.1 sec.
2020-10-11 08:04:45.5: Acquire data for Observation M 42...
2020-10-11 08:04:45.5:   (belongs to Project Messier, Plan M 42)
2020-10-11 08:04:45.6: Send Observation M 42 to ACP Sequencer
2020-10-11 08:04:45.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 08:04:45.6:   Script AcquireScheduler is loaded, and
2020-10-11 08:04:45.6:   the console is idle.
2020-10-11 08:04:45.6:   In start loop, i = 0
2020-10-11 08:04:45.6:   Console is now idle
2020-10-11 08:04:45.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 08:04:45.6:   Script started successfully
2020-10-11 08:04:55.6: Sequencer is now active
2020-10-11 08:11:13.9: Sequencer is no longer active
2020-10-11 08:11:13.9: Post-job status check done (stat=Failed)
2020-10-11 08:11:13.9: ACP data acquisition failed for Observation M 42.
2020-10-11 08:11:13.9:   (Observation M 42 interrupted by acquisition process failure.)
2020-10-11 08:11:14.1: SelectRunning: No running plans
2020-10-11 08:11:14.1: Efficiency: Only one pending plan M 43, it is the "best"
2020-10-11 08:11:14.2: Dispatcher cycle time: 0.1 sec.
2020-10-11 08:11:14.2: Acquire data for Observation M 43...
2020-10-11 08:11:14.2:   (belongs to Project Messier, Plan M 43)
2020-10-11 08:11:14.2: Send Observation M 43 to ACP Sequencer
2020-10-11 08:11:14.2: ACP.RunScript(AcquireScheduler, ):
2020-10-11 08:11:14.2:   Script AcquireScheduler is loaded, and
2020-10-11 08:11:14.2:   the console is idle.
2020-10-11 08:11:14.2:   In start loop, i = 0
2020-10-11 08:11:14.2:   Console is now idle
2020-10-11 08:11:14.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 08:11:14.2:   Script started successfully
2020-10-11 08:11:24.2: Sequencer is now active
2020-10-11 08:17:41.2: Sequencer is no longer active
2020-10-11 08:17:41.2: Post-job status check done (stat=Failed)
2020-10-11 08:17:41.2: ACP data acquisition failed for Observation M 43.
2020-10-11 08:17:41.2:   (Observation M 43 interrupted by acquisition process failure.)
2020-10-11 08:17:41.4: SelectRunning: No running plans
2020-10-11 08:17:41.4: No pending plans that can be started now.
2020-10-11 08:17:41.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:18:11.7: SelectRunning: No running plans
2020-10-11 08:18:11.8: No pending plans that can be started now.
2020-10-11 08:18:11.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:18:42.1: SelectRunning: No running plans
2020-10-11 08:18:42.1: No pending plans that can be started now.
2020-10-11 08:18:42.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:19:12.5: SelectRunning: No running plans
2020-10-11 08:19:12.5: No pending plans that can be started now.
2020-10-11 08:19:12.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:19:42.8: SelectRunning: No running plans
2020-10-11 08:19:42.8: No pending plans that can be started now.
2020-10-11 08:19:42.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:20:13.2: SelectRunning: No running plans
2020-10-11 08:20:13.2: No pending plans that can be started now.
2020-10-11 08:20:13.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:20:43.5: SelectRunning: No running plans
2020-10-11 08:20:43.5: No pending plans that can be started now.
2020-10-11 08:20:43.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:21:13.9: SelectRunning: No running plans
2020-10-11 08:21:13.9: No pending plans that can be started now.
2020-10-11 08:21:13.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:21:44.2: SelectRunning: No running plans
2020-10-11 08:21:44.2: No pending plans that can be started now.
2020-10-11 08:21:44.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:22:14.6: SelectRunning: No running plans
2020-10-11 08:22:14.6: No pending plans that can be started now.
2020-10-11 08:22:14.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:22:44.9: ++ Auto Focus ++
2020-10-11 08:22:44.9: Doing periodic autofocus.
2020-10-11 08:22:44.9: Start special ACP AutoFocus script for scheduler
2020-10-11 08:22:44.9: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 08:22:44.9:   Script AcquireScheduler is loaded, and
2020-10-11 08:22:44.9:   the console is idle.
2020-10-11 08:22:44.9:   In start loop, i = 0
2020-10-11 08:22:44.9:   Console is now idle
2020-10-11 08:22:45.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 08:22:45.0:   Script started successfully
2020-10-11 08:22:55.0: Sequencer is now active
2020-10-11 08:27:40.9: Sequencer is no longer active
2020-10-11 08:27:40.9: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 09:27:21 UTC
2020-10-11 08:27:40.9: SelectRunning: No running plans
2020-10-11 08:27:40.9: Obs KH15D in Plan KH15D was vetoed by HourAngle constraint.
2020-10-11 08:27:40.9:     HourAngle estimates ready after 11-Oct-2020 09:56:24 UTC
2020-10-11 08:27:40.9: Obs KH15D-B in Plan KH15D-B was vetoed by HourAngle constraint.
2020-10-11 08:27:40.9:     HourAngle estimates ready after 11-Oct-2020 10:56:14 UTC
2020-10-11 08:27:41.0: No pending plans that can be started now.
2020-10-11 08:27:41.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:28:11.3: SelectRunning: No running plans
2020-10-11 08:28:11.3: No pending plans that can be started now.
2020-10-11 08:28:11.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:28:41.7: SelectRunning: No running plans
2020-10-11 08:28:41.7: No pending plans that can be started now.
2020-10-11 08:28:41.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:29:12.1: SelectRunning: No running plans
2020-10-11 08:29:12.1: No pending plans that can be started now.
2020-10-11 08:29:12.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:29:42.4: SelectRunning: No running plans
2020-10-11 08:29:42.4: No pending plans that can be started now.
2020-10-11 08:29:42.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:30:12.8: SelectRunning: No running plans
2020-10-11 08:30:12.8: No pending plans that can be started now.
2020-10-11 08:30:12.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:30:43.1: SelectRunning: No running plans
2020-10-11 08:30:43.1: No pending plans that can be started now.
2020-10-11 08:30:43.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:31:13.5: SelectRunning: No running plans
2020-10-11 08:31:13.5: No pending plans that can be started now.
2020-10-11 08:31:13.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:31:43.9: SelectRunning: No running plans
2020-10-11 08:31:43.9: No pending plans that can be started now.
2020-10-11 08:31:43.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:32:14.2: SelectRunning: No running plans
2020-10-11 08:32:14.2: No pending plans that can be started now.
2020-10-11 08:32:14.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:32:44.5: SelectRunning: No running plans
2020-10-11 08:32:44.5: No pending plans that can be started now.
2020-10-11 08:32:44.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:33:14.9: SelectRunning: No running plans
2020-10-11 08:33:14.9: No pending plans that can be started now.
2020-10-11 08:33:14.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:33:45.2: SelectRunning: No running plans
2020-10-11 08:33:45.2: No pending plans that can be started now.
2020-10-11 08:33:45.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:34:15.6: SelectRunning: No running plans
2020-10-11 08:34:15.6: No pending plans that can be started now.
2020-10-11 08:34:15.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:34:45.9: SelectRunning: No running plans
2020-10-11 08:34:45.9: No pending plans that can be started now.
2020-10-11 08:34:45.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:35:16.3: SelectRunning: No running plans
2020-10-11 08:35:16.3: No pending plans that can be started now.
2020-10-11 08:35:16.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:35:46.6: SelectRunning: No running plans
2020-10-11 08:35:46.6: No pending plans that can be started now.
2020-10-11 08:35:46.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:36:17.0: SelectRunning: No running plans
2020-10-11 08:36:17.0: No pending plans that can be started now.
2020-10-11 08:36:17.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:36:47.3: SelectRunning: No running plans
2020-10-11 08:36:47.3: No pending plans that can be started now.
2020-10-11 08:36:47.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:37:17.9: SelectRunning: No running plans
2020-10-11 08:37:17.9: No pending plans that can be started now.
2020-10-11 08:37:17.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:37:48.3: SelectRunning: No running plans
2020-10-11 08:37:48.3: No pending plans that can be started now.
2020-10-11 08:37:48.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:38:18.6: SelectRunning: No running plans
2020-10-11 08:38:18.6: No pending plans that can be started now.
2020-10-11 08:38:18.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:38:49.0: SelectRunning: No running plans
2020-10-11 08:38:49.0: No pending plans that can be started now.
2020-10-11 08:38:49.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:39:19.3: SelectRunning: No running plans
2020-10-11 08:39:19.3: No pending plans that can be started now.
2020-10-11 08:39:19.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:39:49.7: SelectRunning: No running plans
2020-10-11 08:39:49.7: No pending plans that can be started now.
2020-10-11 08:39:49.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:40:20.0: SelectRunning: No running plans
2020-10-11 08:40:20.0: No pending plans that can be started now.
2020-10-11 08:40:20.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:40:50.3: SelectRunning: No running plans
2020-10-11 08:40:50.3: No pending plans that can be started now.
2020-10-11 08:40:50.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:41:20.7: SelectRunning: No running plans
2020-10-11 08:41:20.7: No pending plans that can be started now.
2020-10-11 08:41:20.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:41:51.0: SelectRunning: No running plans
2020-10-11 08:41:51.0: No pending plans that can be started now.
2020-10-11 08:41:51.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:42:21.4: SelectRunning: No running plans
2020-10-11 08:42:21.4: No pending plans that can be started now.
2020-10-11 08:42:21.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:42:51.7: SelectRunning: No running plans
2020-10-11 08:42:51.7: No pending plans that can be started now.
2020-10-11 08:42:51.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:43:22.2: SelectRunning: No running plans
2020-10-11 08:43:22.2: No pending plans that can be started now.
2020-10-11 08:43:22.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:43:52.5: SelectRunning: No running plans
2020-10-11 08:43:52.5: No pending plans that can be started now.
2020-10-11 08:43:52.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:44:22.9: SelectRunning: No running plans
2020-10-11 08:44:22.9: No pending plans that can be started now.
2020-10-11 08:44:22.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:44:53.2: SelectRunning: No running plans
2020-10-11 08:44:53.2: No pending plans that can be started now.
2020-10-11 08:44:53.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:45:23.6: SelectRunning: No running plans
2020-10-11 08:45:23.6: No pending plans that can be started now.
2020-10-11 08:45:23.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:45:54.0: SelectRunning: No running plans
2020-10-11 08:45:54.0: No pending plans that can be started now.
2020-10-11 08:45:54.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:46:24.3: SelectRunning: No running plans
2020-10-11 08:46:24.3: No pending plans that can be started now.
2020-10-11 08:46:24.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:46:54.7: SelectRunning: No running plans
2020-10-11 08:46:54.7: No pending plans that can be started now.
2020-10-11 08:46:54.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:47:25.0: SelectRunning: No running plans
2020-10-11 08:47:25.0: No pending plans that can be started now.
2020-10-11 08:47:25.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:47:55.4: SelectRunning: No running plans
2020-10-11 08:47:55.4: No pending plans that can be started now.
2020-10-11 08:47:55.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:48:25.8: SelectRunning: No running plans
2020-10-11 08:48:25.8: No pending plans that can be started now.
2020-10-11 08:48:25.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:48:56.1: SelectRunning: No running plans
2020-10-11 08:48:56.1: No pending plans that can be started now.
2020-10-11 08:48:56.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:49:26.5: SelectRunning: No running plans
2020-10-11 08:49:26.5: No pending plans that can be started now.
2020-10-11 08:49:26.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:49:56.9: SelectRunning: No running plans
2020-10-11 08:49:56.9: No pending plans that can be started now.
2020-10-11 08:49:56.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:50:27.2: SelectRunning: No running plans
2020-10-11 08:50:27.2: No pending plans that can be started now.
2020-10-11 08:50:27.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:50:57.6: SelectRunning: No running plans
2020-10-11 08:50:57.6: No pending plans that can be started now.
2020-10-11 08:50:57.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:51:27.9: SelectRunning: No running plans
2020-10-11 08:51:27.9: No pending plans that can be started now.
2020-10-11 08:51:27.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:51:58.6: SelectRunning: No running plans
2020-10-11 08:51:58.6: No pending plans that can be started now.
2020-10-11 08:51:58.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:52:28.9: SelectRunning: No running plans
2020-10-11 08:52:28.9: No pending plans that can be started now.
2020-10-11 08:52:28.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:52:59.3: SelectRunning: No running plans
2020-10-11 08:52:59.3: No pending plans that can be started now.
2020-10-11 08:52:59.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:53:29.6: SelectRunning: No running plans
2020-10-11 08:53:29.6: No pending plans that can be started now.
2020-10-11 08:53:29.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:53:59.9: SelectRunning: No running plans
2020-10-11 08:53:59.9: No pending plans that can be started now.
2020-10-11 08:53:59.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:54:30.3: SelectRunning: No running plans
2020-10-11 08:54:30.3: No pending plans that can be started now.
2020-10-11 08:54:30.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:55:00.6: SelectRunning: No running plans
2020-10-11 08:55:00.7: No pending plans that can be started now.
2020-10-11 08:55:00.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:55:31.0: SelectRunning: No running plans
2020-10-11 08:55:31.1: No pending plans that can be started now.
2020-10-11 08:55:31.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:56:01.5: SelectRunning: No running plans
2020-10-11 08:56:01.5: No pending plans that can be started now.
2020-10-11 08:56:01.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:56:31.8: SelectRunning: No running plans
2020-10-11 08:56:31.8: No pending plans that can be started now.
2020-10-11 08:56:31.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:57:02.2: SelectRunning: No running plans
2020-10-11 08:57:02.2: No pending plans that can be started now.
2020-10-11 08:57:02.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:57:32.6: SelectRunning: No running plans
2020-10-11 08:57:32.6: No pending plans that can be started now.
2020-10-11 08:57:32.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:58:02.9: SelectRunning: No running plans
2020-10-11 08:58:02.9: No pending plans that can be started now.
2020-10-11 08:58:02.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:58:33.3: SelectRunning: No running plans
2020-10-11 08:58:33.3: No pending plans that can be started now.
2020-10-11 08:58:33.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:59:03.6: SelectRunning: No running plans
2020-10-11 08:59:03.6: No pending plans that can be started now.
2020-10-11 08:59:03.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 08:59:34.0: SelectRunning: No running plans
2020-10-11 08:59:34.0: No pending plans that can be started now.
2020-10-11 08:59:34.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:00:04.3: SelectRunning: No running plans
2020-10-11 09:00:04.3: No pending plans that can be started now.
2020-10-11 09:00:04.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:00:34.7: SelectRunning: No running plans
2020-10-11 09:00:34.7: No pending plans that can be started now.
2020-10-11 09:00:34.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:01:05.0: SelectRunning: No running plans
2020-10-11 09:01:05.0: No pending plans that can be started now.
2020-10-11 09:01:05.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:01:35.4: SelectRunning: No running plans
2020-10-11 09:01:35.4: No pending plans that can be started now.
2020-10-11 09:01:35.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:02:05.7: SelectRunning: No running plans
2020-10-11 09:02:05.7: No pending plans that can be started now.
2020-10-11 09:02:05.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:02:36.1: SelectRunning: No running plans
2020-10-11 09:02:36.1: No pending plans that can be started now.
2020-10-11 09:02:36.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:03:06.8: SelectRunning: No running plans
2020-10-11 09:03:06.8: No pending plans that can be started now.
2020-10-11 09:03:06.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:03:37.1: SelectRunning: No running plans
2020-10-11 09:03:37.1: No pending plans that can be started now.
2020-10-11 09:03:37.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:04:07.5: SelectRunning: No running plans
2020-10-11 09:04:07.5: No pending plans that can be started now.
2020-10-11 09:04:07.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:04:37.8: SelectRunning: No running plans
2020-10-11 09:04:37.8: No pending plans that can be started now.
2020-10-11 09:04:37.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:05:08.2: SelectRunning: No running plans
2020-10-11 09:05:08.2: No pending plans that can be started now.
2020-10-11 09:05:08.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:05:38.9: SelectRunning: No running plans
2020-10-11 09:05:38.9: No pending plans that can be started now.
2020-10-11 09:05:38.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:06:09.2: SelectRunning: No running plans
2020-10-11 09:06:09.2: No pending plans that can be started now.
2020-10-11 09:06:09.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:06:39.5: SelectRunning: No running plans
2020-10-11 09:06:39.5: No pending plans that can be started now.
2020-10-11 09:06:39.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:07:09.9: SelectRunning: No running plans
2020-10-11 09:07:09.9: No pending plans that can be started now.
2020-10-11 09:07:09.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:07:40.2: SelectRunning: No running plans
2020-10-11 09:07:40.2: No pending plans that can be started now.
2020-10-11 09:07:40.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:08:10.6: SelectRunning: No running plans
2020-10-11 09:08:10.6: No pending plans that can be started now.
2020-10-11 09:08:10.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:08:40.9: SelectRunning: No running plans
2020-10-11 09:08:40.9: No pending plans that can be started now.
2020-10-11 09:08:40.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:09:11.3: SelectRunning: No running plans
2020-10-11 09:09:11.3: No pending plans that can be started now.
2020-10-11 09:09:11.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:09:41.6: SelectRunning: No running plans
2020-10-11 09:09:41.6: No pending plans that can be started now.
2020-10-11 09:09:41.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:10:12.0: SelectRunning: No running plans
2020-10-11 09:10:12.0: No pending plans that can be started now.
2020-10-11 09:10:12.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:10:42.3: SelectRunning: No running plans
2020-10-11 09:10:42.3: No pending plans that can be started now.
2020-10-11 09:10:42.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:11:12.6: SelectRunning: No running plans
2020-10-11 09:11:12.6: No pending plans that can be started now.
2020-10-11 09:11:12.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:11:43.1: SelectRunning: No running plans
2020-10-11 09:11:43.1: No pending plans that can be started now.
2020-10-11 09:11:43.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:12:13.4: SelectRunning: No running plans
2020-10-11 09:12:13.4: No pending plans that can be started now.
2020-10-11 09:12:13.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:12:43.8: SelectRunning: No running plans
2020-10-11 09:12:43.8: No pending plans that can be started now.
2020-10-11 09:12:43.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:13:14.1: SelectRunning: No running plans
2020-10-11 09:13:14.1: No pending plans that can be started now.
2020-10-11 09:13:14.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:13:44.5: SelectRunning: No running plans
2020-10-11 09:13:44.5: No pending plans that can be started now.
2020-10-11 09:13:44.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:14:14.8: SelectRunning: No running plans
2020-10-11 09:14:14.8: No pending plans that can be started now.
2020-10-11 09:14:14.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:14:45.1: SelectRunning: No running plans
2020-10-11 09:14:45.1: No pending plans that can be started now.
2020-10-11 09:14:45.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:15:15.5: SelectRunning: No running plans
2020-10-11 09:15:15.5: No pending plans that can be started now.
2020-10-11 09:15:15.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:15:45.8: SelectRunning: No running plans
2020-10-11 09:15:45.8: No pending plans that can be started now.
2020-10-11 09:15:45.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:16:16.2: SelectRunning: No running plans
2020-10-11 09:16:16.2: No pending plans that can be started now.
2020-10-11 09:16:16.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:16:46.5: SelectRunning: No running plans
2020-10-11 09:16:46.5: No pending plans that can be started now.
2020-10-11 09:16:46.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:17:16.9: SelectRunning: No running plans
2020-10-11 09:17:16.9: No pending plans that can be started now.
2020-10-11 09:17:16.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:17:47.2: SelectRunning: No running plans
2020-10-11 09:17:47.2: No pending plans that can be started now.
2020-10-11 09:17:47.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:18:17.6: SelectRunning: No running plans
2020-10-11 09:18:17.6: No pending plans that can be started now.
2020-10-11 09:18:17.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:18:47.9: SelectRunning: No running plans
2020-10-11 09:18:47.9: No pending plans that can be started now.
2020-10-11 09:18:47.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:19:18.3: SelectRunning: No running plans
2020-10-11 09:19:18.3: No pending plans that can be started now.
2020-10-11 09:19:18.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:19:48.6: SelectRunning: No running plans
2020-10-11 09:19:48.6: No pending plans that can be started now.
2020-10-11 09:19:48.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:20:19.2: SelectRunning: No running plans
2020-10-11 09:20:19.2: No pending plans that can be started now.
2020-10-11 09:20:19.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:20:49.6: SelectRunning: No running plans
2020-10-11 09:20:49.6: No pending plans that can be started now.
2020-10-11 09:20:49.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:21:19.9: SelectRunning: No running plans
2020-10-11 09:21:19.9: No pending plans that can be started now.
2020-10-11 09:21:19.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:21:50.2: SelectRunning: No running plans
2020-10-11 09:21:50.2: No pending plans that can be started now.
2020-10-11 09:21:50.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:22:20.6: SelectRunning: No running plans
2020-10-11 09:22:20.6: No pending plans that can be started now.
2020-10-11 09:22:20.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:22:50.9: SelectRunning: No running plans
2020-10-11 09:22:50.9: No pending plans that can be started now.
2020-10-11 09:22:50.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:23:21.3: SelectRunning: No running plans
2020-10-11 09:23:21.3: No pending plans that can be started now.
2020-10-11 09:23:21.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:23:51.7: SelectRunning: No running plans
2020-10-11 09:23:51.7: No pending plans that can be started now.
2020-10-11 09:23:51.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:24:22.0: SelectRunning: No running plans
2020-10-11 09:24:22.0: No pending plans that can be started now.
2020-10-11 09:24:22.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:24:52.3: SelectRunning: No running plans
2020-10-11 09:24:52.3: No pending plans that can be started now.
2020-10-11 09:24:52.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:25:22.7: SelectRunning: No running plans
2020-10-11 09:25:22.7: No pending plans that can be started now.
2020-10-11 09:25:22.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:25:53.0: SelectRunning: No running plans
2020-10-11 09:25:53.0: No pending plans that can be started now.
2020-10-11 09:25:53.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:26:23.4: SelectRunning: No running plans
2020-10-11 09:26:23.4: No pending plans that can be started now.
2020-10-11 09:26:23.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:26:53.7: SelectRunning: No running plans
2020-10-11 09:26:53.7: No pending plans that can be started now.
2020-10-11 09:26:53.7: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:27:24.1: ++ Auto Focus ++
2020-10-11 09:27:24.1: Doing periodic autofocus.
2020-10-11 09:27:24.1: Start special ACP AutoFocus script for scheduler
2020-10-11 09:27:24.1: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 09:27:24.1:   Script AutoFocusScheduler is loaded, and
2020-10-11 09:27:24.1:   the console is idle.
2020-10-11 09:27:24.1:   In start loop, i = 0
2020-10-11 09:27:24.1:   Console is now idle
2020-10-11 09:27:24.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 09:27:24.1:   Script started successfully
2020-10-11 09:27:34.1: Sequencer is now active
2020-10-11 09:30:37.1: Sequencer is no longer active
2020-10-11 09:30:37.1: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 10:30:22 UTC
2020-10-11 09:30:37.1: SelectRunning: No running plans
2020-10-11 09:30:37.1: No pending plans that can be started now.
2020-10-11 09:30:37.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:31:07.4: SelectRunning: No running plans
2020-10-11 09:31:07.4: No pending plans that can be started now.
2020-10-11 09:31:07.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:31:37.8: SelectRunning: No running plans
2020-10-11 09:31:37.8: No pending plans that can be started now.
2020-10-11 09:31:37.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:32:08.1: SelectRunning: No running plans
2020-10-11 09:32:08.1: No pending plans that can be started now.
2020-10-11 09:32:08.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:32:38.4: SelectRunning: No running plans
2020-10-11 09:32:38.4: No pending plans that can be started now.
2020-10-11 09:32:38.4: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:33:08.8: SelectRunning: No running plans
2020-10-11 09:33:08.8: No pending plans that can be started now.
2020-10-11 09:33:08.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:33:39.1: SelectRunning: No running plans
2020-10-11 09:33:39.1: No pending plans that can be started now.
2020-10-11 09:33:39.1: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:34:09.5: SelectRunning: No running plans
2020-10-11 09:34:09.5: No pending plans that can be started now.
2020-10-11 09:34:09.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:34:39.8: SelectRunning: No running plans
2020-10-11 09:34:39.8: No pending plans that can be started now.
2020-10-11 09:34:39.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:35:10.2: SelectRunning: No running plans
2020-10-11 09:35:10.2: No pending plans that can be started now.
2020-10-11 09:35:10.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:35:40.5: SelectRunning: No running plans
2020-10-11 09:35:40.5: No pending plans that can be started now.
2020-10-11 09:35:40.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:36:10.8: SelectRunning: No running plans
2020-10-11 09:36:10.8: No pending plans that can be started now.
2020-10-11 09:36:10.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:36:41.2: SelectRunning: No running plans
2020-10-11 09:36:41.2: No pending plans that can be started now.
2020-10-11 09:36:41.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:37:11.5: SelectRunning: No running plans
2020-10-11 09:37:11.5: No pending plans that can be started now.
2020-10-11 09:37:11.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:37:41.8: SelectRunning: No running plans
2020-10-11 09:37:41.8: No pending plans that can be started now.
2020-10-11 09:37:41.8: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:38:12.2: SelectRunning: No running plans
2020-10-11 09:38:12.2: No pending plans that can be started now.
2020-10-11 09:38:12.2: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:38:42.5: SelectRunning: No running plans
2020-10-11 09:38:42.5: No pending plans that can be started now.
2020-10-11 09:38:42.5: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:39:12.9: SelectRunning: No running plans
2020-10-11 09:39:12.9: No pending plans that can be started now.
2020-10-11 09:39:12.9: Dispatcher cycle time: 0.0 sec.
2020-10-11 09:39:43.2: SelectRunning: No running plans
2020-10-11 09:39:43.2: Efficiency: Only one pending plan M 82, it is the "best"
2020-10-11 09:39:43.3: Dispatcher cycle time: 0.1 sec.
2020-10-11 09:39:43.3: Acquire data for Observation M 82...
2020-10-11 09:39:43.3:   (belongs to Project Messier, Plan M 82)
2020-10-11 09:39:43.3: Send Observation M 82 to ACP Sequencer
2020-10-11 09:39:43.3: ACP.RunScript(AcquireScheduler, ):
2020-10-11 09:39:43.3:   Script AutoFocusScheduler is loaded, and
2020-10-11 09:39:43.3:   the console is idle.
2020-10-11 09:39:43.3:   In start loop, i = 0
2020-10-11 09:39:43.3:   Console is now idle
2020-10-11 09:39:43.3:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 09:39:43.3:   Script started successfully
2020-10-11 09:39:53.4: Sequencer is now active
2020-10-11 09:48:15.1: Sequencer is no longer active
2020-10-11 09:48:15.1: Post-job status check done (stat=Failed)
2020-10-11 09:48:15.1: ACP data acquisition failed for Observation M 82.
2020-10-11 09:48:15.1:   (Observation M 82 interrupted by acquisition process failure.)
2020-10-11 09:48:15.2: SelectRunning: No running plans
2020-10-11 09:48:15.3: Efficiency: Selecting from 3 pending plans:
2020-10-11 09:48:15.3:   Selected Plan M 79 (TotEff=1.94)
2020-10-11 09:48:15.4: Dispatcher cycle time: 0.2 sec.
2020-10-11 09:48:15.4: Acquire data for Observation M 79...
2020-10-11 09:48:15.4:   (belongs to Project Messier, Plan M 79)
2020-10-11 09:48:15.4: Send Observation M 79 to ACP Sequencer
2020-10-11 09:48:15.4: ACP.RunScript(AcquireScheduler, ):
2020-10-11 09:48:15.4:   Script AcquireScheduler is loaded, and
2020-10-11 09:48:15.4:   the console is idle.
2020-10-11 09:48:15.4:   In start loop, i = 0
2020-10-11 09:48:15.4:   Console is now idle
2020-10-11 09:48:15.4:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 09:48:15.4:   Script started successfully
2020-10-11 09:48:25.4: Sequencer is now active
2020-10-11 09:56:29.3: Sequencer is no longer active
2020-10-11 09:56:29.3: Post-job status check done (stat=Failed)
2020-10-11 09:56:29.3: ACP data acquisition failed for Observation M 79.
2020-10-11 09:56:29.3:   (Observation M 79 interrupted by acquisition process failure.)
2020-10-11 09:56:29.4: SelectRunning: No running plans
2020-10-11 09:56:29.5: Efficiency: Selecting from 3 pending plans:
2020-10-11 09:56:29.6:   Selected Plan KH15D (TotEff=2.03)
2020-10-11 09:56:29.7: Dispatcher cycle time: 0.3 sec.
2020-10-11 09:56:29.7: Acquire data for Observation KH15D...
2020-10-11 09:56:29.7:   (belongs to Project KH15D, Plan KH15D)
2020-10-11 09:56:29.7: Send Observation KH15D to ACP Sequencer
2020-10-11 09:56:29.7: ACP.RunScript(AcquireScheduler, ):
2020-10-11 09:56:29.7:   Script AcquireScheduler is loaded, and
2020-10-11 09:56:29.7:   the console is idle.
2020-10-11 09:56:29.7:   In start loop, i = 0
2020-10-11 09:56:29.7:   Console is now idle
2020-10-11 09:56:29.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 09:56:29.7:   Script started successfully
2020-10-11 09:56:39.7: Sequencer is now active
2020-10-11 10:30:36.9: Sequencer is no longer active
2020-10-11 10:30:36.9: Post-job status check done (stat=Completed)
2020-10-11 10:30:37.0: Acquisition time: 2047.2397027 sec.
2020-10-11 10:30:37.0: Data for Observation KH15D of Plan KH15D acquired successfully.
2020-10-11 10:30:37.0: Plan KH15D completed successfully.
2020-10-11 10:30:37.0: Image Efficiency: 87.9%
2020-10-11 10:30:37.0: Cycle Efficiency: 100.0%
2020-10-11 10:30:37.1: ++ Auto Focus ++
2020-10-11 10:30:37.1: Doing periodic autofocus.
2020-10-11 10:30:37.1: Start special ACP AutoFocus script for scheduler
2020-10-11 10:30:37.1: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 10:30:37.1:   Script AcquireScheduler is loaded, and
2020-10-11 10:30:37.1:   the console is idle.
2020-10-11 10:30:37.1:   In start loop, i = 0
2020-10-11 10:30:37.1:   Console is now idle
2020-10-11 10:30:37.2:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 10:30:37.2:   Script started successfully
2020-10-11 10:30:47.2: Sequencer is now active
2020-10-11 10:34:46.9: Sequencer is no longer active
2020-10-11 10:34:46.9: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 11:34:27 UTC
2020-10-11 10:34:46.9: SelectRunning: No running plans
2020-10-11 10:34:46.9: Obs M 44 in Plan M 44 was vetoed by MoonAvoid constraint.
2020-10-11 10:34:46.9:     MoonAvoid estimates ready after 11-Oct-2020 22:34:46 UTC
2020-10-11 10:34:47.0: Efficiency: Selecting from 4 pending plans:
2020-10-11 10:34:47.0:   Selected Plan M 41 (TotEff=1.50)
2020-10-11 10:34:47.1: Dispatcher cycle time: 0.2 sec.
2020-10-11 10:34:47.1: Acquire data for Observation M 41...
2020-10-11 10:34:47.1:   (belongs to Project Messier, Plan M 41)
2020-10-11 10:34:47.1: Send Observation M 41 to ACP Sequencer
2020-10-11 10:34:47.1: ACP.RunScript(AcquireScheduler, ):
2020-10-11 10:34:47.1:   Script AutoFocusScheduler is loaded, and
2020-10-11 10:34:47.1:   the console is idle.
2020-10-11 10:34:47.1:   In start loop, i = 0
2020-10-11 10:34:47.1:   Console is now idle
2020-10-11 10:34:47.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 10:34:47.1:   Script started successfully
2020-10-11 10:34:57.1: Sequencer is now active
2020-10-11 10:42:22.2: Sequencer is no longer active
2020-10-11 10:42:22.2: Post-job status check done (stat=Failed)
2020-10-11 10:42:22.2: ACP data acquisition failed for Observation M 41.
2020-10-11 10:42:22.2:   (Observation M 41 interrupted by acquisition process failure.)
2020-10-11 10:42:22.3: SelectRunning: No running plans
2020-10-11 10:42:22.4: Efficiency: Selecting from 3 pending plans:
2020-10-11 10:42:22.4:   Selected Plan M 50 (TotEff=1.73)
2020-10-11 10:42:22.5: Dispatcher cycle time: 0.2 sec.
2020-10-11 10:42:22.5: Acquire data for Observation M 50...
2020-10-11 10:42:22.5:   (belongs to Project Messier, Plan M 50)
2020-10-11 10:42:22.5: Send Observation M 50 to ACP Sequencer
2020-10-11 10:42:22.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 10:42:22.5:   Script AcquireScheduler is loaded, and
2020-10-11 10:42:22.5:   the console is idle.
2020-10-11 10:42:22.5:   In start loop, i = 0
2020-10-11 10:42:22.5:   Console is now idle
2020-10-11 10:42:22.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 10:42:22.5:   Script started successfully
2020-10-11 10:42:32.5: Sequencer is now active
2020-10-11 10:49:17.7: Sequencer is no longer active
2020-10-11 10:49:17.7: Post-job status check done (stat=Failed)
2020-10-11 10:49:17.8: ACP data acquisition failed for Observation M 50.
2020-10-11 10:49:17.8:   (Observation M 50 interrupted by acquisition process failure.)
2020-10-11 10:49:17.9: SelectRunning: No running plans
2020-10-11 10:49:18.0: Efficiency: Selecting from 4 pending plans:
2020-10-11 10:49:18.0:   Selected Plan M 47 (TotEff=1.73)
2020-10-11 10:49:18.1: Dispatcher cycle time: 0.2 sec.
2020-10-11 10:49:18.1: Acquire data for Observation M 47...
2020-10-11 10:49:18.1:   (belongs to Project Messier, Plan M 47)
2020-10-11 10:49:18.1: Send Observation M 47 to ACP Sequencer
2020-10-11 10:49:18.1: ACP.RunScript(AcquireScheduler, ):
2020-10-11 10:49:18.1:   Script AcquireScheduler is loaded, and
2020-10-11 10:49:18.1:   the console is idle.
2020-10-11 10:49:18.1:   In start loop, i = 0
2020-10-11 10:49:18.1:   Console is now idle
2020-10-11 10:49:18.1:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 10:49:18.1:   Script started successfully
2020-10-11 10:49:28.1: Sequencer is now active
2020-10-11 10:56:12.5: Sequencer is no longer active
2020-10-11 10:56:12.5: Post-job status check done (stat=Failed)
2020-10-11 10:56:12.5: ACP data acquisition failed for Observation M 47.
2020-10-11 10:56:12.5:   (Observation M 47 interrupted by acquisition process failure.)
2020-10-11 10:56:12.6: SelectRunning: No running plans
2020-10-11 10:56:12.7: Efficiency: Selecting from 4 pending plans:
2020-10-11 10:56:12.8:   Selected Plan M 46 (TotEff=1.74)
2020-10-11 10:56:12.8: Dispatcher cycle time: 0.2 sec.
2020-10-11 10:56:12.8: Acquire data for Observation M 46...
2020-10-11 10:56:12.9:   (belongs to Project Messier, Plan M 46)
2020-10-11 10:56:12.9: Send Observation M 46 to ACP Sequencer
2020-10-11 10:56:12.9: ACP.RunScript(AcquireScheduler, ):
2020-10-11 10:56:12.9:   Script AcquireScheduler is loaded, and
2020-10-11 10:56:12.9:   the console is idle.
2020-10-11 10:56:12.9:   In start loop, i = 0
2020-10-11 10:56:12.9:   Console is now idle
2020-10-11 10:56:12.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 10:56:12.9:   Script started successfully
2020-10-11 10:56:22.9: Sequencer is now active
2020-10-11 11:02:41.6: Sequencer is no longer active
2020-10-11 11:02:41.6: Post-job status check done (stat=Failed)
2020-10-11 11:02:41.6: ACP data acquisition failed for Observation M 46.
2020-10-11 11:02:41.6:   (Observation M 46 interrupted by acquisition process failure.)
2020-10-11 11:02:41.7: SelectRunning: No running plans
2020-10-11 11:02:41.8: Efficiency: Selecting from 4 pending plans:
2020-10-11 11:02:41.9:   Selected Plan KH15D-B (TotEff=2.79)
2020-10-11 11:02:41.9: Dispatcher cycle time: 0.2 sec.
2020-10-11 11:02:41.9: Acquire data for Observation KH15D-B...
2020-10-11 11:02:41.9:   (belongs to Project KH15D, Plan KH15D-B)
2020-10-11 11:02:42.0: Send Observation KH15D-B to ACP Sequencer
2020-10-11 11:02:42.0: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:02:42.0:   Script AcquireScheduler is loaded, and
2020-10-11 11:02:42.0:   the console is idle.
2020-10-11 11:02:42.0:   In start loop, i = 0
2020-10-11 11:02:42.0:   Console is now idle
2020-10-11 11:02:42.0:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:02:42.0:   Script started successfully
2020-10-11 11:02:52.0: Sequencer is now active
2020-10-11 11:23:34.2: Sequencer is no longer active
2020-10-11 11:23:34.2: Post-job status check done (stat=Completed)
2020-10-11 11:23:34.2: Observation had successful requested AF, defer next periodic AF:
2020-10-11 11:23:34.2: Next periodic AF will be ASAP after 3600sec. 11-Oct-2020 12:06:09 UTC
2020-10-11 11:23:34.2: Acquisition time: 1252.2817442 sec.
2020-10-11 11:23:34.2: Data for Observation KH15D-B of Plan KH15D-B acquired successfully.
2020-10-11 11:23:34.3: Plan KH15D-B completed successfully.
2020-10-11 11:23:34.3: Image Efficiency: 71.9%
2020-10-11 11:23:34.3: Cycle Efficiency: 100.0%
2020-10-11 11:23:34.4: SelectRunning: No running plans
2020-10-11 11:23:34.4: Efficiency: Selecting from 3 pending plans:
2020-10-11 11:23:34.5:   Selected Plan M 48 (TotEff=1.67)
2020-10-11 11:23:34.5: Dispatcher cycle time: 0.2 sec.
2020-10-11 11:23:34.5: Acquire data for Observation M 48...
2020-10-11 11:23:34.6:   (belongs to Project Messier, Plan M 48)
2020-10-11 11:23:34.6: Send Observation M 48 to ACP Sequencer
2020-10-11 11:23:34.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:23:34.6:   Script AcquireScheduler is loaded, and
2020-10-11 11:23:34.6:   the console is idle.
2020-10-11 11:23:34.6:   In start loop, i = 0
2020-10-11 11:23:34.6:   Console is now idle
2020-10-11 11:23:34.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:23:34.6:   Script started successfully
2020-10-11 11:23:44.6: Sequencer is now active
2020-10-11 11:30:48.1: Sequencer is no longer active
2020-10-11 11:30:48.1: Post-job status check done (stat=Failed)
2020-10-11 11:30:48.2: ACP data acquisition failed for Observation M 48.
2020-10-11 11:30:48.2:   (Observation M 48 interrupted by acquisition process failure.)
2020-10-11 11:30:48.3: SelectRunning: No running plans
2020-10-11 11:30:48.4: Efficiency: Selecting from 4 pending plans:
2020-10-11 11:30:48.4:   Selected Plan M 67 (TotEff=1.55)
2020-10-11 11:30:48.5: Dispatcher cycle time: 0.2 sec.
2020-10-11 11:30:48.5: Acquire data for Observation M 67...
2020-10-11 11:30:48.5:   (belongs to Project Messier, Plan M 67)
2020-10-11 11:30:48.5: Send Observation M 67 to ACP Sequencer
2020-10-11 11:30:48.5: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:30:48.5:   Script AcquireScheduler is loaded, and
2020-10-11 11:30:48.5:   the console is idle.
2020-10-11 11:30:48.5:   In start loop, i = 0
2020-10-11 11:30:48.5:   Console is now idle
2020-10-11 11:30:48.5:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:30:48.5:   Script started successfully
2020-10-11 11:30:58.5: Sequencer is now active
2020-10-11 11:38:24.3: Sequencer is no longer active
2020-10-11 11:38:24.3: Post-job status check done (stat=Failed)
2020-10-11 11:38:24.3: ACP data acquisition failed for Observation M 67.
2020-10-11 11:38:24.3:   (Observation M 67 interrupted by acquisition process failure.)
2020-10-11 11:38:24.7: SelectRunning: No running plans
2020-10-11 11:38:24.8: Efficiency: Selecting from 3 pending plans:
2020-10-11 11:38:24.8:   Selected Plan M 97 (TotEff=1.48)
2020-10-11 11:38:24.9: Dispatcher cycle time: 0.2 sec.
2020-10-11 11:38:24.9: Acquire data for Observation M 97...
2020-10-11 11:38:24.9:   (belongs to Project Messier, Plan M 97)
2020-10-11 11:38:24.9: Send Observation M 97 to ACP Sequencer
2020-10-11 11:38:24.9: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:38:24.9:   Script AcquireScheduler is loaded, and
2020-10-11 11:38:24.9:   the console is idle.
2020-10-11 11:38:24.9:   In start loop, i = 0
2020-10-11 11:38:24.9:   Console is now idle
2020-10-11 11:38:24.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:38:24.9:   Script started successfully
2020-10-11 11:38:34.9: Sequencer is now active
2020-10-11 11:45:54.6: Sequencer is no longer active
2020-10-11 11:45:54.6: Post-job status check done (stat=Failed)
2020-10-11 11:45:54.6: ACP data acquisition failed for Observation M 97.
2020-10-11 11:45:54.6:   (Observation M 97 interrupted by acquisition process failure.)
2020-10-11 11:45:54.8: SelectRunning: No running plans
2020-10-11 11:45:54.8: Efficiency: Selecting from 2 pending plans:
2020-10-11 11:45:54.8:   Selected Plan M 108 (TotEff=1.49)
2020-10-11 11:45:54.9: Dispatcher cycle time: 0.1 sec.
2020-10-11 11:45:54.9: Acquire data for Observation M 108...
2020-10-11 11:45:54.9:   (belongs to Project Messier, Plan M 108)
2020-10-11 11:45:54.9: Send Observation M 108 to ACP Sequencer
2020-10-11 11:45:54.9: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:45:54.9:   Script AcquireScheduler is loaded, and
2020-10-11 11:45:54.9:   the console is idle.
2020-10-11 11:45:54.9:   In start loop, i = 0
2020-10-11 11:45:54.9:   Console is now idle
2020-10-11 11:45:54.9:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:45:54.9:   Script started successfully
2020-10-11 11:46:04.9: Sequencer is now active
2020-10-11 11:52:49.3: Sequencer is no longer active
2020-10-11 11:52:49.3: Post-job status check done (stat=Failed)
2020-10-11 11:52:49.3: ACP data acquisition failed for Observation M 108.
2020-10-11 11:52:49.3:   (Observation M 108 interrupted by acquisition process failure.)
2020-10-11 11:52:49.5: SelectRunning: No running plans
2020-10-11 11:52:49.5: Efficiency: Only one pending plan M 81, it is the "best"
2020-10-11 11:52:49.6: Dispatcher cycle time: 0.1 sec.
2020-10-11 11:52:49.6: Acquire data for Observation M 81...
2020-10-11 11:52:49.6:   (belongs to Project Messier, Plan M 81)
2020-10-11 11:52:49.6: Send Observation M 81 to ACP Sequencer
2020-10-11 11:52:49.6: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:52:49.6:   Script AcquireScheduler is loaded, and
2020-10-11 11:52:49.6:   the console is idle.
2020-10-11 11:52:49.6:   In start loop, i = 0
2020-10-11 11:52:49.6:   Console is now idle
2020-10-11 11:52:49.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:52:49.6:   Script started successfully
2020-10-11 11:52:59.6: Sequencer is now active
2020-10-11 11:59:58.4: Sequencer is no longer active
2020-10-11 11:59:58.4: Post-job status check done (stat=Failed)
2020-10-11 11:59:58.4: ACP data acquisition failed for Observation M 81.
2020-10-11 11:59:58.4:   (Observation M 81 interrupted by acquisition process failure.)
2020-10-11 11:59:58.6: SelectRunning: No running plans
2020-10-11 11:59:58.6: Efficiency: Only one pending plan M 93, it is the "best"
2020-10-11 11:59:58.7: Dispatcher cycle time: 0.1 sec.
2020-10-11 11:59:58.7: Acquire data for Observation M 93...
2020-10-11 11:59:58.7:   (belongs to Project Messier, Plan M 93)
2020-10-11 11:59:58.7: Send Observation M 93 to ACP Sequencer
2020-10-11 11:59:58.7: ACP.RunScript(AcquireScheduler, ):
2020-10-11 11:59:58.7:   Script AcquireScheduler is loaded, and
2020-10-11 11:59:58.7:   the console is idle.
2020-10-11 11:59:58.7:   In start loop, i = 0
2020-10-11 11:59:58.7:   Console is now idle
2020-10-11 11:59:58.7:   Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully
2020-10-11 11:59:58.7:   Script started successfully
2020-10-11 12:00:08.7: Sequencer is now active
2020-10-11 12:07:45.4: Sequencer is no longer active
2020-10-11 12:07:45.4: Post-job status check done (stat=Failed)
2020-10-11 12:07:45.4: ACP data acquisition failed for Observation M 93.
2020-10-11 12:07:45.5:   (Observation M 93 interrupted by acquisition process failure.)
2020-10-11 12:07:45.6: ++ Auto Focus ++
2020-10-11 12:07:45.6: Doing periodic autofocus.
2020-10-11 12:07:45.6: Start special ACP AutoFocus script for scheduler
2020-10-11 12:07:45.6: ACP.RunScript(AutoFocusScheduler, ):
2020-10-11 12:07:45.6:   Script AcquireScheduler is loaded, and
2020-10-11 12:07:45.6:   the console is idle.
2020-10-11 12:07:45.6:   In start loop, i = 0
2020-10-11 12:07:45.6:   Console is now idle
2020-10-11 12:07:45.6:   Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully
2020-10-11 12:07:45.6:   Script started successfully
2020-10-11 12:07:55.6: Sequencer is now active
2020-10-11 12:11:54.3: Sequencer is no longer active
2020-10-11 12:11:54.3: Next periodic autofocus ASAP after 3600sec. 11-Oct-2020 13:11:35 UTC
2020-10-11 12:11:54.3: SelectRunning: No running plans
2020-10-11 12:11:54.3: No pending plans that can be started now.
2020-10-11 12:11:54.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:12:24.6: SelectRunning: No running plans
2020-10-11 12:12:24.6: No pending plans that can be started now.
2020-10-11 12:12:24.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:12:55.3: SelectRunning: No running plans
2020-10-11 12:12:55.3: No pending plans that can be started now.
2020-10-11 12:12:55.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:13:25.6: SelectRunning: No running plans
2020-10-11 12:13:25.6: No pending plans that can be started now.
2020-10-11 12:13:25.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:13:56.0: SelectRunning: No running plans
2020-10-11 12:13:56.0: No pending plans that can be started now.
2020-10-11 12:13:56.0: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:14:26.3: SelectRunning: No running plans
2020-10-11 12:14:26.3: Plan M 109 would extend past daylight, deferred to next night
2020-10-11 12:14:26.3: No pending plans that can be started now.
2020-10-11 12:14:26.3: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:14:56.6: SelectRunning: No running plans
2020-10-11 12:14:56.6: No pending plans that can be started now.
2020-10-11 12:14:56.6: Dispatcher cycle time: 0.0 sec.
2020-10-11 12:27:04.9: ++ Dawn Flats ++
2020-10-11 12:27:04.9: Start ACP AutoFlat script for Dawn flats
2020-10-11 12:27:04.9: ACP.RunScript(AutoFlat, C:\Users\admin\Documents\ACP Astronomy\Plans\SchedulerDawnFlats.txt):
2020-10-11 12:27:04.9:   Script AutoFocusScheduler is loaded, and
2020-10-11 12:27:04.9:   the console is idle.
2020-10-11 12:27:04.9:   In start loop, i = 0
2020-10-11 12:27:04.9:   Console is now idle
2020-10-11 12:27:05.0:   Loaded C:\Program Files (x86)\ACP Obs Control\Scripts\AutoFlat.vbs successfully
2020-10-11 12:27:05.1:   Set parameter "C:\Users\admin\Documents\ACP Astronomy\Plans\SchedulerDawnFlats.txt" successfully
2020-10-11 12:27:05.1:   Script started successfully
2020-10-11 12:27:15.1: Sequencer is now active
2020-10-11 12:44:51.5: ** Script interrupted by weather safety event.
2020-10-11 12:46:15.2: Sequencer is no longer active
2020-10-11 12:46:15.3: -- Weather Unsafe --
2020-10-11 12:46:46.6: -- Observatory Shutdown --
2020-10-11 12:46:46.6: Start ACP Sequencer's ShutdownObs script
2020-10-11 12:46:46.6: ACP.RunScript(ShutdownObs, ):
2020-10-11 12:46:46.6:   Script ACP-Weather is loaded, and
2020-10-11 12:46:46.6:   the console is idle.
2020-10-11 12:46:46.6:   In start loop, i = 0
2020-10-11 12:46:46.6:   Console is now idle
2020-10-11 12:46:46.6:   Loaded C:\Users\Public\Documents\ACP Config\Scheduler\ShutdownObs.js successfully
2020-10-11 12:46:46.6:   Script started successfully
2020-10-11 12:46:56.7: Sequencer is now active
2020-10-11 12:47:01.7: Sequencer is no longer active
2020-10-12 01:22:35.9: ++ Weather Safe ++
2020-10-12 02:02:26.4: Run statistics:
2020-10-12 02:02:26.4:   Observations:   112
2020-10-12 02:02:26.4:   Considered:     2
2020-10-12 02:02:26.4:   Completed:      0
2020-10-12 02:02:26.4:   Skipped:        0
2020-10-12 02:02:26.4:   Never Eligible: 110
2020-10-12 02:02:26.4:   Failed:         2
Log closed at Mon, Oct 12 2020 02:02:26 UTC (actual time)