Log opened at Fri, Dec 16 2022 03:20:38 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-16 03:20:38.6: REAL TIME internal clock initialized at 16-Dec-2022 03:20:38 UTC
2022-12-16 03:20:38.6: Dispatcher started at 16-Dec-2022 03:20:38 UTC
2022-12-16 03:20:38.6: Loaded Constraint plugin AirMass
2022-12-16 03:20:38.6: Loaded Constraint plugin AirmassRange
2022-12-16 03:20:38.7: Loaded Constraint plugin Horizon
2022-12-16 03:20:38.7: Loaded Constraint plugin HourAngle
2022-12-16 03:20:38.7: Loaded Constraint plugin MoonAvoid
2022-12-16 03:20:38.7: Loaded Constraint plugin MoonDown
2022-12-16 03:20:38.7: Loaded Constraint plugin SkyCondition
2022-12-16 03:20:38.7: Loaded Constraint plugin TimeRange
2022-12-16 03:20:39.5: 233 plans are now pending.
2022-12-16 03:20:39.5: Attach ACP sequencer
2022-12-16 03:20:45.6: Weather successfully connected. Wait for valid data...
2022-12-16 03:20:46.6: ==== Dispatcher is running in NORMAL (NIGHT) mode ====
2022-12-16 03:20:46.6: Sequencer does sky flats
2022-12-16 03:20:50.7: ++ Weather Safe ++
2022-12-16 03:20:57.7: ++ Observatory Startup ++
2022-12-16 03:20:57.7: Weather safety is now disabled
2022-12-16 03:20:57.7: Start ACP Sequencer's StartupObs script
2022-12-16 03:21:58.9: Weather safety is re-enabled
2022-12-16 03:21:58.9: Weather safety is now disabled
2022-12-16 03:21:58.9: Open the observatory dome/roof
2022-12-16 03:22:59.6: Weather safety is re-enabled
2022-12-16 03:22:59.6: Observatory startup succeeded, and dome opened successfully
2022-12-16 03:22:59.6: Periodic AF schedule reset. Interval is now 1.0 hours
2022-12-16 03:22:59.6: Last AutoFocus was completed at 15-Dec-2022 12:07:06 UTC
2022-12-16 03:22:59.6: Autofocus will be done ASAP.
2022-12-16 03:22:59.6: ++ Auto Focus ++
2022-12-16 03:22:59.6: Doing periodic autofocus.
2022-12-16 03:22:59.6: Start special ACP AutoFocus script for scheduler
2022-12-16 03:29:35.8: Next periodic autofocus ASAP after 3600sec. 16-Dec-2022 04:29:17 UTC
2022-12-16 03:29:37.9: Rising plan delay enabled for Plan AO_Aur:
2022-12-16 03:29:37.9:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:37.9:     565 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:37.9:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:20 UTC
2022-12-16 03:29:37.9: Rising plan delay enabled for Plan AO_Aur:
2022-12-16 03:29:37.9:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:37.9:     565 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:37.9:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:20 UTC
2022-12-16 03:29:38.0: Rising plan delay enabled for Plan ER_Aur:
2022-12-16 03:29:38.0:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.0:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.0:     but only 183 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.0:     deferred (rising) for 182 min, until 16-Dec-2022 06:32:10 UTC
2022-12-16 03:29:38.0: Rising plan delay enabled for Plan ER_Aur:
2022-12-16 03:29:38.0:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.0:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.0:     but only 183 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.0:     deferred (rising) for 182 min, until 16-Dec-2022 06:32:10 UTC
2022-12-16 03:29:38.0: Rising plan delay enabled for Plan FS_Aur:
2022-12-16 03:29:38.0:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.0:     563 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.0:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:09 UTC
2022-12-16 03:29:38.0: Rising plan delay enabled for Plan FS_Aur:
2022-12-16 03:29:38.0:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.0:     563 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.0:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:09 UTC
2022-12-16 03:29:38.0: Rising plan delay enabled for Plan GR_Ori:
2022-12-16 03:29:38.0:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.0:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.0:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:31 UTC
2022-12-16 03:29:38.0: Rising plan delay enabled for Plan GR_Ori:
2022-12-16 03:29:38.0:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.0:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.0:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:31 UTC
2022-12-16 03:29:38.1: Rising plan delay enabled for Plan HQ_Per:
2022-12-16 03:29:38.1:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.1:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.1:     but only 154 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.1:     deferred (rising) for 153 min, until 16-Dec-2022 06:03:03 UTC
2022-12-16 03:29:38.1: Rising plan delay enabled for Plan HQ_Per:
2022-12-16 03:29:38.1:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.1:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.1:     but only 154 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.1:     deferred (rising) for 153 min, until 16-Dec-2022 06:03:03 UTC
2022-12-16 03:29:38.1: Rising plan delay enabled for Plan J072816:
2022-12-16 03:29:38.1:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.1:     547 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.1:     deferred (rising) for 190 min, until 16-Dec-2022 06:40:33 UTC
2022-12-16 03:29:38.1: Rising plan delay enabled for Plan J072816:
2022-12-16 03:29:38.1:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.1:     547 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.1:     deferred (rising) for 190 min, until 16-Dec-2022 06:40:33 UTC
2022-12-16 03:29:38.3: Rising plan delay enabled for Plan NSVS_0421121:
2022-12-16 03:29:38.3:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.3:     557 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.3:     but only 130 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.3:     deferred (rising) for 129 min, until 16-Dec-2022 05:38:48 UTC
2022-12-16 03:29:38.3: Rising plan delay enabled for Plan NSVS_0421121:
2022-12-16 03:29:38.3:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.3:     557 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.3:     but only 130 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.4:     deferred (rising) for 129 min, until 16-Dec-2022 05:38:48 UTC
2022-12-16 03:29:38.4: Rising plan delay enabled for Plan QZ_Aur:
2022-12-16 03:29:38.4:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.4:     543 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.4:     but only 191 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.4:     deferred (rising) for 188 min, until 16-Dec-2022 06:37:49 UTC
2022-12-16 03:29:38.4: Rising plan delay enabled for Plan QZ_Aur:
2022-12-16 03:29:38.4:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.4:     543 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.4:     but only 191 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.4:     deferred (rising) for 188 min, until 16-Dec-2022 06:37:49 UTC
2022-12-16 03:29:38.4: Rising plan delay enabled for Plan S5_0716+714:
2022-12-16 03:29:38.4:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.4:     569 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.4:     deferred (rising) for 193 min, until 16-Dec-2022 06:42:48 UTC
2022-12-16 03:29:38.4: Rising plan delay enabled for Plan SU_Lyn:
2022-12-16 03:29:38.4:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.4:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.4:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:31 UTC
2022-12-16 03:29:38.4: Rising plan delay enabled for Plan SU_Lyn:
2022-12-16 03:29:38.4:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.4:     567 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.4:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:36 UTC
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan TIC35022727:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     203 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only -113 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     ending rising plan delay. Plan is now eligible.
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan TT_Ari:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     558 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only -2 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     ending rising plan delay. Plan is now eligible.
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan TT_Ari:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     552 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only -5 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     ending rising plan delay. Plan is now eligible.
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan TV_Cam:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only 118 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     deferred (rising) for 117 min, until 16-Dec-2022 05:26:43 UTC
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan TV_Cam:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only 118 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     deferred (rising) for 117 min, until 16-Dec-2022 05:26:43 UTC
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan TV_Cam:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     559 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only 118 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     deferred (rising) for 117 min, until 16-Dec-2022 05:26:43 UTC
2022-12-16 03:29:38.5: Rising plan delay enabled for Plan V1112_Per:
2022-12-16 03:29:38.5:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.5:     557 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.5:     but only 139 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.5:     deferred (rising) for 137 min, until 16-Dec-2022 05:47:08 UTC
2022-12-16 03:29:38.6: Rising plan delay enabled for Plan V504_Per:
2022-12-16 03:29:38.6:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.6:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.6:     but only 15 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.6:     deferred (rising) for 15 min, until 16-Dec-2022 03:44:50 UTC
2022-12-16 03:29:38.6: Rising plan delay enabled for Plan V504_Per:
2022-12-16 03:29:38.6:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.6:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.6:     but only 15 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.6:     deferred (rising) for 15 min, until 16-Dec-2022 03:44:50 UTC
2022-12-16 03:29:38.6: Rising plan delay enabled for Plan V582_Aur:
2022-12-16 03:29:38.6:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.6:     563 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.6:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:13 UTC
2022-12-16 03:29:38.6: Rising plan delay enabled for Plan V582_Aur:
2022-12-16 03:29:38.6:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.6:     563 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.6:     deferred (rising) for 192 min, until 16-Dec-2022 06:42:13 UTC
2022-12-16 03:29:38.7: Rising plan delay enabled for Plan YZ_Aur:
2022-12-16 03:29:38.7:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.7:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.7:     but only 189 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.7:     deferred (rising) for 188 min, until 16-Dec-2022 06:38:27 UTC
2022-12-16 03:29:38.7: Rising plan delay enabled for Plan YZ_Aur:
2022-12-16 03:29:38.7:     193 min. remaining to meet strict constraints.
2022-12-16 03:29:38.7:     566 min. remaining for the plan to fit before dawn.
2022-12-16 03:29:38.7:     but only 189 min till start so Plan transits mid-way in its time span.
2022-12-16 03:29:38.7:     deferred (rising) for 188 min, until 16-Dec-2022 06:38:27 UTC
2022-12-16 03:29:38.8: Dispatcher cycle time: 3.1 sec.
2022-12-16 03:29:38.8: Acquire data for Observation DW_Cas...
2022-12-16 03:29:38.8:   (belongs to Project AAVSO_survey_HQ_AUTO_ver2706, Plan DW_Cas)
2022-12-16 03:29:38.8: Send Observation DW_Cas to ACP Sequencer
2022-12-16 05:40:08.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2022-12-16 05:40:48.1: ACP data acquisition failed for Observation DW_Cas.
2022-12-16 05:40:48.1:   (Observation DW_Cas interrupted by acquisition process failure.)
2022-12-16 05:41:07.7: Weather successfully connected. Wait for valid data...
2022-12-16 05:41:11.4: ACPSequencer: Failed to get ACP's DispatcherEnabled property. ACP is compromised.
2022-12-16 05:41:11.4:               The callee (server [not server application]) is not available and disappeared; all connections are invalid. The call may have executed. (Exception from HRESULT: 0x80010007 (RPC_E_SERVER_DIED))
2022-12-16 05:41:12.8: **ACP HAS BECOME COMPROMISED:
2022-12-16 05:41:12.8:   Scheduler depends on ACP and yet ACP is frozen, dead, or otherwise compromised.
2022-12-16 05:41:12.8:   If this happens again, please look at ACP. Is it running? Is a fatal error box visible? Is it frozen?
2022-12-16 05:41:12.8:   Usually this is a result of a device or USB failure or lock-up.
2022-12-16 05:41:12.8: No operator intervention command tool specified
2022-12-16 05:41:13.0: Run statistics:
2022-12-16 05:41:13.0:   Observations:   299
2022-12-16 05:41:13.0:   Considered:     2
2022-12-16 05:41:13.0:   Completed:      0
2022-12-16 05:41:13.0:   Skipped:        0
2022-12-16 05:41:13.0:   Never Eligible: 297
2022-12-16 05:41:13.0:   Failed:         2
2022-12-16 05:41:13.0: Shutter-Open efficiency: 0.00%
2022-12-16 05:41:13.0: Overall Efficiency: 99.90%
Log closed at Fri, Dec 16 2022 05:41:16 UTC (actual time)