Log opened at Thu, Dec 15 2022 01:10:10 UTC (actual time) Current log level is Normal ACP Expert Scheduler Engine Version 9.0.4 Part of ACP Expert 9.0 Release Full license: Arne Henden 2022-12-15 01:10:10.9: REAL TIME internal clock initialized at 15-Dec-2022 01:10:10 UTC 2022-12-15 01:10:11.0: Dispatcher started at 15-Dec-2022 01:10:11 UTC 2022-12-15 01:10:11.2: Loaded Constraint plugin AirMass 2022-12-15 01:10:11.2: Loaded Constraint plugin AirmassRange 2022-12-15 01:10:11.2: Loaded Constraint plugin Horizon 2022-12-15 01:10:11.2: Loaded Constraint plugin HourAngle 2022-12-15 01:10:11.3: Loaded Constraint plugin MoonAvoid 2022-12-15 01:10:11.3: Loaded Constraint plugin MoonDown 2022-12-15 01:10:11.3: Loaded Constraint plugin SkyCondition 2022-12-15 01:10:11.3: Loaded Constraint plugin TimeRange 2022-12-15 01:10:12.1: 4 plans are now pending. 2022-12-15 01:10:12.1: Attach ACP sequencer 2022-12-15 01:10:18.6: ++ Weather Safe ++ 2022-12-15 01:10:18.9: ==== Dispatcher is running in NORMAL (NIGHT) mode ==== 2022-12-15 01:10:18.9: Sequencer does sky flats 2022-12-15 01:10:25.7: ++ Observatory Startup ++ 2022-12-15 01:10:25.7: Weather safety is now disabled 2022-12-15 01:10:25.7: Start ACP Sequencer's StartupObs script 2022-12-15 01:10:40.9: Weather safety is re-enabled 2022-12-15 01:10:40.9: Weather safety is now disabled 2022-12-15 01:10:40.9: Open the observatory dome/roof 2022-12-15 01:11:41.6: Weather safety is re-enabled 2022-12-15 01:11:41.6: Observatory startup succeeded, and dome opened successfully 2022-12-15 01:11:41.7: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:11:41.7: 274 min. remaining to meet strict constraints. 2022-12-15 01:11:41.7: 525 min. remaining for the plan to fit before dawn. 2022-12-15 01:11:41.7: but only 120 min till start so Plan transits mid-way in its time span. 2022-12-15 01:11:41.7: deferred (rising) for 102 min, until 15-Dec-2022 02:54:03 UTC 2022-12-15 01:11:41.7: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:11:41.7: 274 min. remaining to meet strict constraints. 2022-12-15 01:11:41.7: 433 min. remaining for the plan to fit before dawn. 2022-12-15 01:11:41.7: but only 74 min till start so Plan transits mid-way in its time span. 2022-12-15 01:11:41.7: deferred (rising) for 46 min, until 15-Dec-2022 01:58:33 UTC 2022-12-15 01:11:41.7: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:11:41.7: 274 min. remaining to meet strict constraints. 2022-12-15 01:11:41.7: 340 min. remaining for the plan to fit before dawn. 2022-12-15 01:11:41.7: but only 28 min till start so Plan transits mid-way in its time span. 2022-12-15 01:11:41.7: ending rising plan delay. Plan is now eligible. 2022-12-15 01:11:41.7: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:11:41.7: 274 min. remaining to meet strict constraints. 2022-12-15 01:11:41.7: 248 min. remaining for the plan to fit before dawn. 2022-12-15 01:11:41.7: but only -17 min till start so Plan transits mid-way in its time span. 2022-12-15 01:11:41.7: ending rising plan delay. Plan is now eligible. 2022-12-15 01:11:41.8: Dispatcher cycle time: 0.2 sec. 2022-12-15 01:11:41.8: Acquire data for Observation TIC35022727... 2022-12-15 01:11:41.8: (belongs to Project Transit, Plan TIC35022727) 2022-12-15 01:11:41.8: Send Observation TIC35022727 to ACP Sequencer 2022-12-15 01:14:35.3: ACP ERROR: Run failed for unknown reason (see ACP run log) 2022-12-15 01:14:35.4: ACP data acquisition failed for Observation TIC35022727. 2022-12-15 01:14:35.4: (Observation TIC35022727 interrupted by acquisition process failure.) 2022-12-15 01:14:35.5: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:14:35.5: 272 min. remaining to meet strict constraints. 2022-12-15 01:14:35.5: 245 min. remaining for the plan to fit before dawn. 2022-12-15 01:14:35.5: but only -20 min till start so Plan transits mid-way in its time span. 2022-12-15 01:14:35.5: ending rising plan delay. Plan is now eligible. 2022-12-15 01:14:35.5: Dispatcher cycle time: 0.0 sec. 2022-12-15 01:14:35.5: Acquire data for Observation TIC35022727... 2022-12-15 01:14:35.5: (belongs to Project Transit, Plan TIC35022727) 2022-12-15 01:14:35.5: Send Observation TIC35022727 to ACP Sequencer 2022-12-15 01:14:48.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2022-12-15 01:15:24.1: RUN FAILED: Scheduler was stopped during run 2022-12-15 01:15:24.3: ACP data acquisition failed for Observation TIC35022727. 2022-12-15 01:15:24.3: (Observation TIC35022727 interrupted by operator or system.) 2022-12-15 01:17:06.6: ++ Observatory Startup ++ 2022-12-15 01:17:06.6: Weather safety is now disabled 2022-12-15 01:17:06.6: Start ACP Sequencer's StartupObs script 2022-12-15 01:17:21.7: Weather safety is re-enabled 2022-12-15 01:17:21.7: Observatory startup succeeded 2022-12-15 01:17:21.7: Periodic AF schedule reset. Interval is now 1.0 hours 2022-12-15 01:17:21.7: Last AutoFocus was completed at 14-Dec-2022 03:45:35 UTC 2022-12-15 01:17:21.7: Autofocus will be done ASAP. 2022-12-15 01:17:21.7: ++ Auto Focus ++ 2022-12-15 01:17:21.7: Doing periodic autofocus. 2022-12-15 01:17:21.7: Start special ACP AutoFocus script for scheduler 2022-12-15 01:21:44.0: Next periodic autofocus ASAP after 3600sec. 15-Dec-2022 02:21:25 UTC 2022-12-15 01:21:44.1: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:21:44.1: 264 min. remaining to meet strict constraints. 2022-12-15 01:21:44.1: 515 min. remaining for the plan to fit before dawn. 2022-12-15 01:21:44.1: but only 110 min till start so Plan transits mid-way in its time span. 2022-12-15 01:21:44.1: deferred (rising) for 92 min, until 15-Dec-2022 02:54:03 UTC 2022-12-15 01:21:44.1: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:21:44.1: 264 min. remaining to meet strict constraints. 2022-12-15 01:21:44.1: 423 min. remaining for the plan to fit before dawn. 2022-12-15 01:21:44.1: but only 64 min till start so Plan transits mid-way in its time span. 2022-12-15 01:21:44.1: deferred (rising) for 36 min, until 15-Dec-2022 01:58:33 UTC 2022-12-15 01:21:44.1: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:21:44.1: 264 min. remaining to meet strict constraints. 2022-12-15 01:21:44.1: 330 min. remaining for the plan to fit before dawn. 2022-12-15 01:21:44.1: but only 18 min till start so Plan transits mid-way in its time span. 2022-12-15 01:21:44.1: ending rising plan delay. Plan is now eligible. 2022-12-15 01:21:44.1: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:21:44.1: 264 min. remaining to meet strict constraints. 2022-12-15 01:21:44.1: 238 min. remaining for the plan to fit before dawn. 2022-12-15 01:21:44.1: but only -27 min till start so Plan transits mid-way in its time span. 2022-12-15 01:21:44.1: ending rising plan delay. Plan is now eligible. 2022-12-15 01:21:44.1: Dispatcher cycle time: 0.1 sec. 2022-12-15 01:21:44.1: Acquire data for Observation TIC35022727... 2022-12-15 01:21:44.1: (belongs to Project Transit, Plan TIC35022727) 2022-12-15 01:21:44.1: Send Observation TIC35022727 to ACP Sequencer 2022-12-15 01:25:18.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2022-12-15 01:25:54.3: RUN FAILED: Scheduler was stopped during run 2022-12-15 01:25:54.4: ACP data acquisition failed for Observation TIC35022727. 2022-12-15 01:25:54.4: (Observation TIC35022727 interrupted by operator or system.) 2022-12-15 01:27:07.3: ++ Observatory Startup ++ 2022-12-15 01:27:07.3: Weather safety is now disabled 2022-12-15 01:27:07.3: Start ACP Sequencer's StartupObs script 2022-12-15 01:27:22.7: Weather safety is re-enabled 2022-12-15 01:27:22.7: Observatory startup succeeded 2022-12-15 01:27:22.7: Periodic AF schedule reset. Interval is now 1.0 hours 2022-12-15 01:27:22.7: Last AutoFocus was completed at 15-Dec-2022 01:21:25 UTC 2022-12-15 01:27:22.7: Next AutoFocus in 54.0 minutes or ASAP after that. 2022-12-15 01:27:22.8: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:27:22.8: 259 min. remaining to meet strict constraints. 2022-12-15 01:27:22.8: 509 min. remaining for the plan to fit before dawn. 2022-12-15 01:27:22.8: but only 105 min till start so Plan transits mid-way in its time span. 2022-12-15 01:27:22.8: deferred (rising) for 86 min, until 15-Dec-2022 02:54:03 UTC 2022-12-15 01:27:22.9: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:27:22.9: 259 min. remaining to meet strict constraints. 2022-12-15 01:27:22.9: 417 min. remaining for the plan to fit before dawn. 2022-12-15 01:27:22.9: but only 59 min till start so Plan transits mid-way in its time span. 2022-12-15 01:27:22.9: deferred (rising) for 31 min, until 15-Dec-2022 01:58:33 UTC 2022-12-15 01:27:22.9: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:27:22.9: 259 min. remaining to meet strict constraints. 2022-12-15 01:27:22.9: 324 min. remaining for the plan to fit before dawn. 2022-12-15 01:27:22.9: but only 12 min till start so Plan transits mid-way in its time span. 2022-12-15 01:27:22.9: ending rising plan delay. Plan is now eligible. 2022-12-15 01:27:22.9: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:27:22.9: 259 min. remaining to meet strict constraints. 2022-12-15 01:27:22.9: 232 min. remaining for the plan to fit before dawn. 2022-12-15 01:27:22.9: but only -33 min till start so Plan transits mid-way in its time span. 2022-12-15 01:27:22.9: ending rising plan delay. Plan is now eligible. 2022-12-15 01:27:22.9: Dispatcher cycle time: 0.1 sec. 2022-12-15 01:27:22.9: Acquire data for Observation TIC35022727... 2022-12-15 01:27:22.9: (belongs to Project Transit, Plan TIC35022727) 2022-12-15 01:27:22.9: Send Observation TIC35022727 to ACP Sequencer 2022-12-15 01:29:37.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2022-12-15 01:29:44.0: ** ACP script "AcquireScheduler" failed: 2022-12-15 01:29:44.0: **Script Error** Source: ACP Message: The script was aborted. Location: line 1899 column 5. 2022-12-15 01:29:44.0: RUN FAILED: Scheduler was stopped during run 2022-12-15 01:29:44.1: ACP data acquisition failed for Observation TIC35022727. 2022-12-15 01:29:44.1: (Observation TIC35022727 interrupted by operator or system.) 2022-12-15 01:33:16.1: ++ Observatory Startup ++ 2022-12-15 01:33:16.1: Weather safety is now disabled 2022-12-15 01:33:16.1: Start ACP Sequencer's StartupObs script 2022-12-15 01:33:31.3: Weather safety is re-enabled 2022-12-15 01:33:31.3: Observatory startup succeeded 2022-12-15 01:33:31.3: Periodic AF schedule reset. Interval is now 1.0 hours 2022-12-15 01:33:31.3: Last AutoFocus was completed at 15-Dec-2022 01:21:25 UTC 2022-12-15 01:33:31.3: Next AutoFocus in 47.9 minutes or ASAP after that. 2022-12-15 01:33:31.4: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:33:31.4: 253 min. remaining to meet strict constraints. 2022-12-15 01:33:31.4: 503 min. remaining for the plan to fit before dawn. 2022-12-15 01:33:31.4: but only 99 min till start so Plan transits mid-way in its time span. 2022-12-15 01:33:31.4: deferred (rising) for 80 min, until 15-Dec-2022 02:54:03 UTC 2022-12-15 01:33:31.4: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:33:31.4: 253 min. remaining to meet strict constraints. 2022-12-15 01:33:31.4: 411 min. remaining for the plan to fit before dawn. 2022-12-15 01:33:31.4: but only 52 min till start so Plan transits mid-way in its time span. 2022-12-15 01:33:31.4: deferred (rising) for 25 min, until 15-Dec-2022 01:58:33 UTC 2022-12-15 01:33:31.4: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:33:31.4: 253 min. remaining to meet strict constraints. 2022-12-15 01:33:31.4: 318 min. remaining for the plan to fit before dawn. 2022-12-15 01:33:31.4: but only 6 min till start so Plan transits mid-way in its time span. 2022-12-15 01:33:31.4: ending rising plan delay. Plan is now eligible. 2022-12-15 01:33:31.4: Rising plan delay enabled for Plan TIC35022727: 2022-12-15 01:33:31.4: 253 min. remaining to meet strict constraints. 2022-12-15 01:33:31.4: 226 min. remaining for the plan to fit before dawn. 2022-12-15 01:33:31.4: but only -39 min till start so Plan transits mid-way in its time span. 2022-12-15 01:33:31.4: ending rising plan delay. Plan is now eligible. 2022-12-15 01:33:31.5: Dispatcher cycle time: 0.2 sec. 2022-12-15 01:33:31.5: Acquire data for Observation TIC35022727... 2022-12-15 01:33:31.5: (belongs to Project Transit, Plan TIC35022727) 2022-12-15 01:33:31.5: Send Observation TIC35022727 to ACP Sequencer 2022-12-15 01:35:59.2: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2022-12-15 01:36:35.6: RUN FAILED: Scheduler was stopped during run 2022-12-15 01:36:35.7: **EXCEPTION IN SCHEDULER: 2022-12-15 01:36:35.7: ACP Has failed to acquire data more than 4 times in a row. Something is really wrong! 2022-12-15 01:36:35.7: Traceback: at DC3.Scheduler.Engine.DoSchedulePass() at DC3.Scheduler.Engine.Run() 2022-12-15 01:36:35.7: Weather safety is now disabled 2022-12-15 01:36:35.7: Close the observatory dome/roof. 2022-12-15 01:37:21.7: Weather safety is re-enabled 2022-12-15 01:37:21.8: No operator intervention command tool specified 2022-12-15 01:37:21.8: Run statistics: 2022-12-15 01:37:21.8: Observations: 4 2022-12-15 01:37:21.8: Considered: 0 2022-12-15 01:37:21.8: Completed: 0 2022-12-15 01:37:21.8: Skipped: 0 2022-12-15 01:37:21.8: Never Eligible: 4 2022-12-15 01:37:21.8: Failed: 0 2022-12-15 01:37:21.8: Shutter-Open efficiency: 0.00% 2022-12-15 01:37:21.8: Overall Efficiency: 99.90% 2022-12-15 01:37:21.8: Release ACP sequencer 2022-12-15 01:37:21.9: ACPSequencer: Failed to get ACP's DispatcherEnabled property. ACP is compromised. 2022-12-15 01:37:21.9: Object reference not set to an instance of an object. Log closed at Thu, Dec 15 2022 01:37:23 UTC (actual time)