So I've now had this happen three times. The issue appears to be that if a weather unsafe event occurs during the period (say 1 minute) that it takes to open the observatory, Scheduler goes into a user intervention mode and reports a failure.
Code:
Log opened at Sun, Apr 10 2016 03:43:12 UTC (actual time)Current log level is Verbose
ACP Expert Scheduler version 8.0.3
Full license: Todd Benko10-Apr-2016 03:43:12.2:
Simulated clock initialized at 10-Apr-2016 03:43:12 UTC10-Apr-2016 03:43:12.2:
Loaded Constraint plugin AirMass10-Apr-2016 03:43:12.3:
Loaded Constraint plugin AirmassRange10-Apr-2016 03:43:12.3:
Loaded Constraint plugin Horizon10-Apr-2016 03:43:12.3:
Loaded Constraint plugin HourAngle10-Apr-2016 03:43:12.3:
Loaded Constraint plugin MoonAvoid10-Apr-2016 03:43:12.3:
Loaded Constraint plugin MoonDown10-Apr-2016 03:43:12.3:
Loaded Constraint plugin SkyCondition10-Apr-2016 03:43:12.3:
Loaded Constraint plugin TimeRange10-Apr-2016 03:43:13.3:
36 plans are now pending.10-Apr-2016 03:43:13.3:
Attach ACP sequencer10-Apr-2016 03:43:19.3:
There is no controllable dome or roof.10-Apr-2016 03:43:19.4:
++ Weather Safe ++10-Apr-2016 03:43:21.2:
Sequencer does screen/panel flats10-Apr-2016 03:43:36.7:
[Horizon] Target will never rise above specified elevation10-Apr-2016 03:43:36.7:
[Horizon] Target will never rise above specified elevation10-Apr-2016 03:43:36.7:
[Horizon] Target will never rise above specified elevation10-Apr-2016 03:43:36.8:
Plan NGC 4490-B 240s (1-7) is rising, with 436 min. remaining for the plan to fit before dawn.10-Apr-2016 03:43:36.8:
but only 172 min till Plan time-centroid transits.10-Apr-2016 03:43:36.8:
deferred (rising) for 168 min, until 10-Apr-2016 06:32:26 UTC10-Apr-2016 03:43:36.8: Plan ngc 4490-Ha 900s (1-4) is rising, with 405 min. remaining for the plan to fit before dawn.10-Apr-2016 03:43:36.8:
but only 156 min till Plan time-centroid transits.10-Apr-2016 03:43:36.8:
deferred (rising) for 150 min, until 10-Apr-2016 06:13:59 UTC10-Apr-2016 03:43:36.8: Plan ngc 4490-Ha 900s (5-8) is rising, with 405 min. remaining for the plan to fit before dawn.10-Apr-2016 03:43:36.8:
but only 156 min till Plan time-centroid transits.10-Apr-2016 03:43:36.8:
deferred (rising) for 150 min, until 10-Apr-2016 06:13:59 UTC10-Apr-2016 03:43:36.8: Plan NGC 4490-Lum 600s (1-6) is rising, with 405 min. remaining for the plan to fit before dawn.
10-Apr-2016 03:43:36.8: but only 156 min till Plan time-centroid transits.
10-Apr-2016 03:43:36.8: deferred (rising) for 149 min, until 10-Apr-2016 06:13:29 UTC10-Apr-2016 03:43:37.2: Dispatcher cycle time: 1.6 sec.
10-Apr-2016 03:43:37.2: Acquire data for Observation ngc 2300...
10-Apr-2016 03:43:37.2: (belongs to Project ngc 2300, Plan ngc 2300-G 240s (1-7))
10-Apr-2016 03:43:37.2: ++ Observatory Startup ++
10-Apr-2016 03:43:37.2: Start ACP Sequencer's StartupObs script
10-Apr-2016 03:45:26.8: Open the observatory dome/roof
10-Apr-2016 03:45:46.9: **Weather went unsafe while dome opening.
10-Apr-2016 03:45:46.9: Special-needs building, run CloseSpecialRoof script to get it closed.
10-Apr-2016 03:46:33.5: ** Script interrupted by weather safety event.
10-Apr-2016 03:46:39.7: **EXCEPTION IN SCHEDULER:
10-Apr-2016 03:46:39.7: Property DomeOpen: Unexpected shutter opening status
10-Apr-2016 03:46:39.7: Traceback:
at DC3.Scheduler.ACPSequencer.get_DomeOpen()
at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
at DC3.Scheduler.Engine.DoSchedulePass()
at DC3.Scheduler.Engine.Run()
10-Apr-2016 03:46:39.7: Release ACP sequencerLog closed at Sun, Apr 10 2016 03:46:41 UTC (actual time)
The problem only happens when sky goes unsafe during the opening event. If the dome completed the open and then it reported unsafe it all works correctly. So the issue is one of timing of the weather unsafe interrupting the dome command control before the correct reported status is allowed to report.
Todd