Log opened at Tue, Dec 17 2019 05:03:59 UTC (actual time) Current log level is Debug ACP Expert Scheduler version 8.2.3 Full license: Arne Henden 2019-12-17 05:04:00.2: ++ Observatory Startup ++ 2019-12-17 05:04:00.2: Start ACP Sequencer's StartupObs script 2019-12-17 05:04:00.2: ACP.RunScript(StartupObs, ): 2019-12-17 05:04:00.2: Script ACP-Weather is loaded, and 2019-12-17 05:04:00.2: the console is idle. 2019-12-17 05:04:00.2: In start loop, i = 0 2019-12-17 05:04:00.2: Console is now idle 2019-12-17 05:04:00.4: Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully 2019-12-17 05:04:00.4: Script started successfully 2019-12-17 05:04:10.4: Sequencer is now active 2019-12-17 05:04:29.7: Sequencer is no longer active 2019-12-17 05:04:29.7: Open the observatory dome/roof 2019-12-17 05:05:45.4: Observatory startup succeeded, and dome opened successfully 2019-12-17 05:05:45.4: ++ Auto Focus ++ 2019-12-17 05:05:45.4: Doing initial autofocus. 2019-12-17 05:05:45.4: Start special ACP AutoFocus script for scheduler 2019-12-17 05:05:45.4: ACP.RunScript(AutoFocusScheduler, ): 2019-12-17 05:05:45.4: Script StartupObs is loaded, and 2019-12-17 05:05:45.4: the console is idle. 2019-12-17 05:05:45.4: In start loop, i = 0 2019-12-17 05:05:45.4: Console is now idle 2019-12-17 05:05:45.4: Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-12-17 05:05:45.5: Script started successfully 2019-12-17 05:05:55.5: Sequencer is now active 2019-12-17 05:14:02.5: Sequencer is no longer active 2019-12-17 05:14:02.5: Next periodic autofocus ASAP after 3600sec. 17-Dec-2019 06:13:47 UTC 2019-12-17 05:14:02.5: Plan KH15D has Monitor Mode. Time to resubmit it. 2019-12-17 05:14:02.5: Plan Test has Monitor Mode. Time to resubmit it. 2019-12-17 05:14:02.5: Plan KH15D-B has Monitor Mode. Time to resubmit it. 2019-12-17 05:14:02.5: SelectRunning: No running plans 2019-12-17 05:14:02.5: Obs KH15D in Plan KH15D was vetoed by HourAngle constraint. 2019-12-17 05:14:02.5: HourAngle estimates ready after 17-Dec-2019 05:35:57 UTC 2019-12-17 05:14:02.6: Obs KH15D-B in Plan KH15D-B was vetoed by HourAngle constraint. 2019-12-17 05:14:02.6: HourAngle estimates ready after 17-Dec-2019 06:35:47 UTC 2019-12-17 05:14:02.6: Obs SA109 in Plan SA109 was vetoed by AirMass constraint. 2019-12-17 05:14:02.6: AirMass estimates ready after 17-Dec-2019 15:34:29 UTC 2019-12-17 05:14:02.6: Obs Test in Plan Test vetoed for sequencer hard limit. 2019-12-17 05:14:02.6: (deferred for 71 min, until 17-Dec-2019 06:25:02 UTC 2019-12-17 05:14:02.6: No pending plans that can be started now. 2019-12-17 05:14:02.6: Dispatcher cycle time: 0.1 sec. 2019-12-17 05:14:33.2: SelectRunning: No running plans 2019-12-17 05:14:33.2: No pending plans that can be started now. 2019-12-17 05:14:33.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:15:03.8: SelectRunning: No running plans 2019-12-17 05:15:03.8: No pending plans that can be started now. 2019-12-17 05:15:03.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:15:34.4: SelectRunning: No running plans 2019-12-17 05:15:34.4: No pending plans that can be started now. 2019-12-17 05:15:34.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:16:05.0: SelectRunning: No running plans 2019-12-17 05:16:05.0: No pending plans that can be started now. 2019-12-17 05:16:05.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:16:35.9: SelectRunning: No running plans 2019-12-17 05:16:35.9: No pending plans that can be started now. 2019-12-17 05:16:35.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:17:06.5: SelectRunning: No running plans 2019-12-17 05:17:06.5: No pending plans that can be started now. 2019-12-17 05:17:06.5: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:17:37.1: SelectRunning: No running plans 2019-12-17 05:17:37.1: No pending plans that can be started now. 2019-12-17 05:17:37.1: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:18:07.7: SelectRunning: No running plans 2019-12-17 05:18:07.7: No pending plans that can be started now. 2019-12-17 05:18:07.7: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:18:38.2: SelectRunning: No running plans 2019-12-17 05:18:38.3: No pending plans that can be started now. 2019-12-17 05:18:38.3: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:19:08.8: SelectRunning: No running plans 2019-12-17 05:19:08.8: No pending plans that can be started now. 2019-12-17 05:19:08.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:19:39.4: SelectRunning: No running plans 2019-12-17 05:19:39.4: No pending plans that can be started now. 2019-12-17 05:19:39.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:20:10.0: SelectRunning: No running plans 2019-12-17 05:20:10.0: No pending plans that can be started now. 2019-12-17 05:20:10.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:20:40.6: SelectRunning: No running plans 2019-12-17 05:20:40.6: No pending plans that can be started now. 2019-12-17 05:20:40.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:21:11.2: SelectRunning: No running plans 2019-12-17 05:21:11.2: No pending plans that can be started now. 2019-12-17 05:21:11.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:21:41.8: SelectRunning: No running plans 2019-12-17 05:21:41.8: No pending plans that can be started now. 2019-12-17 05:21:41.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:22:12.4: SelectRunning: No running plans 2019-12-17 05:22:12.4: No pending plans that can be started now. 2019-12-17 05:22:12.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:22:43.0: SelectRunning: No running plans 2019-12-17 05:22:43.0: No pending plans that can be started now. 2019-12-17 05:22:43.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:23:13.6: SelectRunning: No running plans 2019-12-17 05:23:13.6: No pending plans that can be started now. 2019-12-17 05:23:13.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:23:44.4: SelectRunning: No running plans 2019-12-17 05:23:44.4: No pending plans that can be started now. 2019-12-17 05:23:44.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:24:15.0: SelectRunning: No running plans 2019-12-17 05:24:15.0: No pending plans that can be started now. 2019-12-17 05:24:15.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:24:45.6: SelectRunning: No running plans 2019-12-17 05:24:45.6: No pending plans that can be started now. 2019-12-17 05:24:45.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:25:16.6: SelectRunning: No running plans 2019-12-17 05:25:16.6: No pending plans that can be started now. 2019-12-17 05:25:16.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:25:47.2: SelectRunning: No running plans 2019-12-17 05:25:47.2: No pending plans that can be started now. 2019-12-17 05:25:47.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:26:17.7: SelectRunning: No running plans 2019-12-17 05:26:17.7: No pending plans that can be started now. 2019-12-17 05:26:17.7: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:26:48.7: SelectRunning: No running plans 2019-12-17 05:26:48.7: No pending plans that can be started now. 2019-12-17 05:26:48.7: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:27:19.3: SelectRunning: No running plans 2019-12-17 05:27:19.3: No pending plans that can be started now. 2019-12-17 05:27:19.3: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:27:49.9: SelectRunning: No running plans 2019-12-17 05:27:49.9: No pending plans that can be started now. 2019-12-17 05:27:49.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:28:20.8: SelectRunning: No running plans 2019-12-17 05:28:20.8: No pending plans that can be started now. 2019-12-17 05:28:20.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:28:51.4: SelectRunning: No running plans 2019-12-17 05:28:51.4: No pending plans that can be started now. 2019-12-17 05:28:51.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:29:22.0: SelectRunning: No running plans 2019-12-17 05:29:22.0: No pending plans that can be started now. 2019-12-17 05:29:22.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:29:52.9: SelectRunning: No running plans 2019-12-17 05:29:52.9: No pending plans that can be started now. 2019-12-17 05:29:52.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:30:23.5: SelectRunning: No running plans 2019-12-17 05:30:23.5: No pending plans that can be started now. 2019-12-17 05:30:23.5: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:30:54.1: SelectRunning: No running plans 2019-12-17 05:30:54.1: No pending plans that can be started now. 2019-12-17 05:30:54.1: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:31:25.0: SelectRunning: No running plans 2019-12-17 05:31:25.0: No pending plans that can be started now. 2019-12-17 05:31:25.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:31:55.6: SelectRunning: No running plans 2019-12-17 05:31:55.6: No pending plans that can be started now. 2019-12-17 05:31:55.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:32:26.2: SelectRunning: No running plans 2019-12-17 05:32:26.2: No pending plans that can be started now. 2019-12-17 05:32:26.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:32:56.8: SelectRunning: No running plans 2019-12-17 05:32:56.8: No pending plans that can be started now. 2019-12-17 05:32:56.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:33:27.3: SelectRunning: No running plans 2019-12-17 05:33:27.3: No pending plans that can be started now. 2019-12-17 05:33:27.3: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:33:57.9: SelectRunning: No running plans 2019-12-17 05:33:57.9: No pending plans that can be started now. 2019-12-17 05:33:57.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:34:28.5: SelectRunning: No running plans 2019-12-17 05:34:28.5: No pending plans that can be started now. 2019-12-17 05:34:28.5: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:34:59.1: SelectRunning: No running plans 2019-12-17 05:34:59.1: No pending plans that can be started now. 2019-12-17 05:34:59.1: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:35:29.7: SelectRunning: No running plans 2019-12-17 05:35:29.7: No pending plans that can be started now. 2019-12-17 05:35:29.7: Dispatcher cycle time: 0.0 sec. 2019-12-17 05:36:00.3: SelectRunning: No running plans 2019-12-17 05:36:00.3: Efficiency: Only one pending plan KH15D, it is the "best" 2019-12-17 05:36:00.3: Dispatcher cycle time: 0.1 sec. 2019-12-17 05:36:00.3: Acquire data for Observation KH15D... 2019-12-17 05:36:00.3: (belongs to Project KH15D, Plan KH15D) 2019-12-17 05:36:00.4: Send Observation KH15D to ACP Sequencer 2019-12-17 05:36:00.4: ACP.RunScript(AcquireScheduler, ): 2019-12-17 05:36:00.4: Script AutoFocusScheduler is loaded, and 2019-12-17 05:36:00.4: the console is idle. 2019-12-17 05:36:00.4: In start loop, i = 0 2019-12-17 05:36:00.4: Console is now idle 2019-12-17 05:36:00.4: Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully 2019-12-17 05:36:00.4: Script started successfully 2019-12-17 05:36:10.4: Sequencer is now active 2019-12-17 07:24:45.1: ** Script interrupted by weather safety event. 2019-12-17 07:25:39.7: Sequencer is no longer active 2019-12-17 07:26:10.2: Post-job status check done (stat=Running) 2019-12-17 07:26:10.2: ACP ABORT: Acquisition process was interrupted by weather unsafe. 2019-12-17 07:26:10.2: ACP data acquisition failed for Observation KH15D. 2019-12-17 07:26:10.2: (Plan KH15D will be resubmitted in its entirety. 2019-12-17 07:26:10.2: (Observation KH15D interrupted by weather unsafe event.) 2019-12-17 07:26:10.5: -- Weather Unsafe -- 2019-12-17 07:45:03.3: ++ Weather Safe ++ 2019-12-17 07:45:04.4: ++ Observatory Startup ++ 2019-12-17 07:45:04.4: Start ACP Sequencer's StartupObs script 2019-12-17 07:45:04.4: ACP.RunScript(StartupObs, ): 2019-12-17 07:45:04.4: Script ACP-Weather is loaded, and 2019-12-17 07:45:04.4: the console is idle. 2019-12-17 07:45:04.4: In start loop, i = 0 2019-12-17 07:45:04.4: Console is now idle 2019-12-17 07:45:04.5: Loaded C:\Users\Public\Documents\ACP Config\Scheduler\StartupObs.js successfully 2019-12-17 07:45:04.5: Script started successfully 2019-12-17 07:45:14.5: Sequencer is now active 2019-12-17 07:45:27.0: Sequencer is no longer active 2019-12-17 07:45:27.0: Open the observatory dome/roof 2019-12-17 07:46:57.8: Observatory startup succeeded, and dome opened successfully 2019-12-17 07:46:57.8: ++ Auto Focus ++ 2019-12-17 07:46:57.8: Doing initial autofocus. 2019-12-17 07:46:57.8: Start special ACP AutoFocus script for scheduler 2019-12-17 07:46:57.8: ACP.RunScript(AutoFocusScheduler, ): 2019-12-17 07:46:57.8: Script StartupObs is loaded, and 2019-12-17 07:46:57.8: the console is idle. 2019-12-17 07:46:57.8: In start loop, i = 0 2019-12-17 07:46:57.8: Console is now idle 2019-12-17 07:46:57.8: Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-12-17 07:46:57.8: Script started successfully 2019-12-17 07:47:07.8: Sequencer is now active 2019-12-17 07:47:48.3: Sequencer is no longer active 2019-12-17 07:47:48.3: ** ACP script "AutoFocusScheduler" failed: 2019-12-17 07:47:48.3: **Script Error (Tracking has been stopped)** Source: ASCOM.SiTechDll.Telescope Message: sttgaSiTech Says: Motor(s) in Blinky Mode! Location: line 81 column 2. 2019-12-17 07:47:48.3: ** Autofocus failed (n=1), will retry ASAP after 0 min. 2019-12-17 07:47:48.4: SelectRunning: No running plans 2019-12-17 07:47:48.4: Obs Test in Plan Test vetoed for sequencer hard limit. 2019-12-17 07:47:48.4: (deferred for 71 min, until 17-Dec-2019 08:58:48 UTC 2019-12-17 07:47:48.4: Efficiency: Selecting from 2 pending plans: 2019-12-17 07:47:48.5: Selected Plan KH15D-B (TotEff=0.00) 2019-12-17 07:47:48.5: Dispatcher cycle time: 0.2 sec. 2019-12-17 07:47:48.5: Acquire data for Observation KH15D-B... 2019-12-17 07:47:48.5: (belongs to Project KH15D, Plan KH15D-B) 2019-12-17 07:47:48.6: ++ Auto Focus ++ 2019-12-17 07:47:48.6: Doing periodic autofocus. 2019-12-17 07:47:48.6: Start special ACP AutoFocus script for scheduler 2019-12-17 07:47:48.6: ACP.RunScript(AutoFocusScheduler, ): 2019-12-17 07:47:48.6: Script AutoFocusScheduler is loaded, and 2019-12-17 07:47:48.6: the console is idle. 2019-12-17 07:47:48.6: In start loop, i = 0 2019-12-17 07:47:48.6: Console is now idle 2019-12-17 07:47:48.6: Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-12-17 07:47:48.6: Script started successfully 2019-12-17 07:47:58.6: Sequencer is now active 2019-12-17 07:48:19.1: Sequencer is no longer active 2019-12-17 07:48:19.1: ** ACP script "AutoFocusScheduler" failed: 2019-12-17 07:48:19.1: **Script Error (Tracking has been stopped)** Source: ASCOM.SiTechDll.Telescope Message: sttgaSiTech Says: Motor(s) in Blinky Mode! Location: line 77 column 9. 2019-12-17 07:48:19.1: ** Autofocus failed (n=2), will retry ASAP after 5 min. 2019-12-17 07:48:19.1: Send Observation KH15D-B to ACP Sequencer 2019-12-17 07:48:19.1: ACP.RunScript(AcquireScheduler, ): 2019-12-17 07:48:19.1: Script AutoFocusScheduler is loaded, and 2019-12-17 07:48:19.1: the console is idle. 2019-12-17 07:48:19.1: In start loop, i = 0 2019-12-17 07:48:19.1: Console is now idle 2019-12-17 07:48:19.1: Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully 2019-12-17 07:48:19.1: Script started successfully 2019-12-17 07:48:29.2: Sequencer is now active 2019-12-17 07:48:46.4: Sequencer is no longer active 2019-12-17 07:48:46.4: ** ACP script "AcquireScheduler" failed: 2019-12-17 07:48:46.4: **Script Error (Tracking has been stopped)** Source: ASCOM.SiTechDll.Telescope Message: sttgaSiTech Says: Motor(s) in Blinky Mode! Location: line 2046 column 9. 2019-12-17 07:48:46.4: ACP ERROR: Run failed due to script error (see ACP run log) 2019-12-17 07:48:46.4: Observation's requested AF failed. Next periodic AF still 17-Dec-2019 07:53:19 UTC 2019-12-17 07:48:46.4: ACP data acquisition failed for Observation KH15D-B. 2019-12-17 07:48:46.4: (Observation KH15D-B interrupted by acquisition process failure.) 2019-12-17 07:48:46.8: SelectRunning: No running plans 2019-12-17 07:48:46.8: Efficiency: Only one pending plan KH15D, it is the "best" 2019-12-17 07:48:46.9: Dispatcher cycle time: 0.1 sec. 2019-12-17 07:48:46.9: Acquire data for Observation KH15D... 2019-12-17 07:48:46.9: (belongs to Project KH15D, Plan KH15D) 2019-12-17 07:48:46.9: Send Observation KH15D to ACP Sequencer 2019-12-17 07:48:46.9: ACP.RunScript(AcquireScheduler, ): 2019-12-17 07:48:46.9: Script AcquireScheduler is loaded, and 2019-12-17 07:48:46.9: the console is idle. 2019-12-17 07:48:46.9: In start loop, i = 0 2019-12-17 07:48:46.9: Console is now idle 2019-12-17 07:48:46.9: Loaded C:\Program Files (x86)\ACP Scheduler\AcquireScheduler.vbs successfully 2019-12-17 07:48:46.9: Script started successfully 2019-12-17 07:48:56.9: Sequencer is now active 2019-12-17 07:49:19.5: Sequencer is no longer active 2019-12-17 07:49:19.5: ** ACP script "AcquireScheduler" failed: 2019-12-17 07:49:19.5: **Script Error (Tracking has been stopped)** Source: ASCOM.SiTechDll.Telescope Message: sttgaSiTech Says: Motor(s) in Blinky Mode! Location: line 2046 column 9. 2019-12-17 07:49:19.5: ACP ERROR: Run failed due to script error (see ACP run log) 2019-12-17 07:49:19.5: ACP data acquisition failed for Observation KH15D. 2019-12-17 07:49:19.5: (Observation KH15D interrupted by acquisition process failure.) 2019-12-17 07:49:19.6: SelectRunning: No running plans 2019-12-17 07:49:19.6: No pending plans that can be started now. 2019-12-17 07:49:19.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:49:50.2: SelectRunning: No running plans 2019-12-17 07:49:50.2: No pending plans that can be started now. 2019-12-17 07:49:50.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:50:20.8: SelectRunning: No running plans 2019-12-17 07:50:20.8: No pending plans that can be started now. 2019-12-17 07:50:20.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:50:51.6: SelectRunning: No running plans 2019-12-17 07:50:51.6: No pending plans that can be started now. 2019-12-17 07:50:51.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:51:22.2: SelectRunning: No running plans 2019-12-17 07:51:22.2: No pending plans that can be started now. 2019-12-17 07:51:22.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:51:52.7: SelectRunning: No running plans 2019-12-17 07:51:52.7: No pending plans that can be started now. 2019-12-17 07:51:52.7: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:52:23.3: SelectRunning: No running plans 2019-12-17 07:52:23.3: No pending plans that can be started now. 2019-12-17 07:52:23.3: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:52:53.9: SelectRunning: No running plans 2019-12-17 07:52:53.9: No pending plans that can be started now. 2019-12-17 07:52:53.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:53:24.5: ++ Auto Focus ++ 2019-12-17 07:53:24.6: Doing periodic autofocus. 2019-12-17 07:53:24.6: Start special ACP AutoFocus script for scheduler 2019-12-17 07:53:24.6: ACP.RunScript(AutoFocusScheduler, ): 2019-12-17 07:53:24.6: Script AcquireScheduler is loaded, and 2019-12-17 07:53:24.6: the console is idle. 2019-12-17 07:53:24.6: In start loop, i = 0 2019-12-17 07:53:24.6: Console is now idle 2019-12-17 07:53:24.6: Loaded C:\Program Files (x86)\ACP Scheduler\AutoFocusScheduler.vbs successfully 2019-12-17 07:53:24.6: Script started successfully 2019-12-17 07:53:34.6: Sequencer is now active 2019-12-17 07:53:39.7: Sequencer is no longer active 2019-12-17 07:53:39.7: ** ACP script "AutoFocusScheduler" failed: 2019-12-17 07:53:39.7: **Script Error (Tracking has been stopped)** Source: ASCOM.SiTechDll.Telescope Message: sttgaSiTech Says: Motor(s) in Blinky Mode! Location: line 77 column 9. 2019-12-17 07:53:39.7: ** Autofocus failed (n=3), will retry ASAP after 10 min. 2019-12-17 07:53:39.7: SelectRunning: No running plans 2019-12-17 07:53:39.7: No pending plans that can be started now. 2019-12-17 07:53:39.7: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:54:10.2: SelectRunning: No running plans 2019-12-17 07:54:10.2: No pending plans that can be started now. 2019-12-17 07:54:10.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:54:40.8: SelectRunning: No running plans 2019-12-17 07:54:40.8: No pending plans that can be started now. 2019-12-17 07:54:40.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:55:11.4: SelectRunning: No running plans 2019-12-17 07:55:11.4: No pending plans that can be started now. 2019-12-17 07:55:11.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:55:42.0: SelectRunning: No running plans 2019-12-17 07:55:42.0: No pending plans that can be started now. 2019-12-17 07:55:42.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:56:12.6: SelectRunning: No running plans 2019-12-17 07:56:12.6: No pending plans that can be started now. 2019-12-17 07:56:12.6: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:56:43.2: SelectRunning: No running plans 2019-12-17 07:56:43.2: No pending plans that can be started now. 2019-12-17 07:56:43.2: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:57:13.8: SelectRunning: No running plans 2019-12-17 07:57:13.8: No pending plans that can be started now. 2019-12-17 07:57:13.8: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:57:44.3: SelectRunning: No running plans 2019-12-17 07:57:44.3: No pending plans that can be started now. 2019-12-17 07:57:44.3: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:58:14.9: SelectRunning: No running plans 2019-12-17 07:58:14.9: No pending plans that can be started now. 2019-12-17 07:58:14.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:58:45.5: SelectRunning: No running plans 2019-12-17 07:58:45.5: No pending plans that can be started now. 2019-12-17 07:58:45.5: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:59:16.1: SelectRunning: No running plans 2019-12-17 07:59:16.1: No pending plans that can be started now. 2019-12-17 07:59:16.1: Dispatcher cycle time: 0.0 sec. 2019-12-17 07:59:46.9: SelectRunning: No running plans 2019-12-17 07:59:46.9: No pending plans that can be started now. 2019-12-17 07:59:46.9: Dispatcher cycle time: 0.0 sec. 2019-12-17 08:00:17.4: SelectRunning: No running plans 2019-12-17 08:00:17.4: No pending plans that can be started now. 2019-12-17 08:00:17.4: Dispatcher cycle time: 0.0 sec. 2019-12-17 08:00:48.0: SelectRunning: No running plans 2019-12-17 08:00:48.0: No pending plans that can be started now. 2019-12-17 08:00:48.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 08:01:19.0: SelectRunning: No running plans 2019-12-17 08:01:19.0: No pending plans that can be started now. 2019-12-17 08:01:19.0: Dispatcher cycle time: 0.0 sec. 2019-12-17 08:02:01.6: -- Weather Unsafe -- 2019-12-17 08:26:53.1: ++ Weather Safe ++ 2019-12-17 08:33:56.8: ++ Observatory Startup ++ 2019-12-17 08:33:57.3: Start ACP Sequencer's StartupObs script 2019-12-17 08:33:58.9: ACP.RunScript(StartupObs, ): 2019-12-17 08:33:58.9: Script ACP-Weather is loaded, and 2019-12-17 08:33:58.9: the console is RUNNING. 2019-12-17 08:33:58.9: In start loop, i = 0 2019-12-17 08:33:59.0: Console is busy, and foundIdle is False 2019-12-17 08:33:59.2: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:34:59.2: In start loop, i = 1 2019-12-17 08:34:59.3: Console is busy, and foundIdle is False 2019-12-17 08:34:59.5: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:35:59.6: In start loop, i = 2 2019-12-17 08:35:59.6: Console is busy, and foundIdle is False 2019-12-17 08:35:59.9: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:36:59.9: In start loop, i = 3 2019-12-17 08:36:59.9: Console is busy, and foundIdle is False 2019-12-17 08:37:00.2: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:38:00.2: In start loop, i = 4 2019-12-17 08:38:00.2: Console is busy, and foundIdle is False 2019-12-17 08:38:00.4: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:39:00.4: In start loop, i = 5 2019-12-17 08:39:00.5: Console is busy, and foundIdle is False 2019-12-17 08:39:00.7: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:40:00.7: In start loop, i = 6 2019-12-17 08:40:00.8: Console is busy, and foundIdle is False 2019-12-17 08:40:01.0: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:41:01.0: In start loop, i = 7 2019-12-17 08:41:01.1: Console is busy, and foundIdle is False 2019-12-17 08:41:01.3: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:42:01.3: In start loop, i = 8 2019-12-17 08:42:01.3: Console is busy, and foundIdle is False 2019-12-17 08:42:01.6: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:43:01.6: In start loop, i = 9 2019-12-17 08:43:01.6: Console is busy, and foundIdle is False 2019-12-17 08:43:01.8: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:44:01.9: In start loop, i = 10 2019-12-17 08:44:01.9: Console is busy, and foundIdle is False 2019-12-17 08:44:02.1: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:45:02.1: In start loop, i = 11 2019-12-17 08:45:02.2: Console is busy, and foundIdle is False 2019-12-17 08:45:02.4: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:46:02.4: In start loop, i = 12 2019-12-17 08:46:02.4: Console is busy, and foundIdle is False 2019-12-17 08:46:02.6: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:47:02.7: In start loop, i = 13 2019-12-17 08:47:02.7: Console is busy, and foundIdle is False 2019-12-17 08:47:02.9: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:48:02.9: In start loop, i = 14 2019-12-17 08:48:03.0: Console is busy, and foundIdle is False 2019-12-17 08:48:03.2: WARNING: Request sent while Weather Safety Script is running 2019-12-17 08:49:03.2: ERROR: Failed to start ACP script due to ACP being busy or locked 2019-12-17 09:03:02.2: -- Weather Unsafe -- 2019-12-17 09:03:03.6: **EXCEPTION IN SCHEDULER: 2019-12-17 09:03:03.6: Observatory startup was interrupted by a weather unsafe event. 2019-12-17 09:03:04.8: Traceback: at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome) at DC3.Scheduler.Engine.Run() 2019-12-17 09:11:55.3: No operator intervention command tool specified 2019-12-17 09:11:55.8: Run statistics: 2019-12-17 09:11:55.8: Observations: 4 2019-12-17 09:11:55.8: Considered: 2 2019-12-17 09:11:55.8: Completed: 0 2019-12-17 09:11:55.8: Skipped: 0 2019-12-17 09:11:55.8: Never Eligible: 2 2019-12-17 09:11:55.8: Failed: 2 2019-12-17 09:11:55.8: Shutter-Open efficiency: 10.03% 2019-12-17 09:11:55.8: Overall Efficiency: 27.73% 2019-12-17 09:11:55.8: Release ACP sequencer Log closed at Tue, Dec 17 2019 09:11:58 UTC (actual time)