Log opened at Wed, Feb 27 2019 01:38:05 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-02-27 01:38:05.0: Internal clock initialized at 27-Feb-2019 01:38:05 UTC
2019-02-27 01:38:05.0: Loaded Constraint plugin AirMass
2019-02-27 01:38:05.0: Loaded Constraint plugin AirmassRange
2019-02-27 01:38:05.0: Loaded Constraint plugin Horizon
2019-02-27 01:38:05.1: Loaded Constraint plugin HourAngle
2019-02-27 01:38:05.1: Loaded Constraint plugin MoonAvoid
2019-02-27 01:38:05.1: Loaded Constraint plugin MoonDown
2019-02-27 01:38:05.1: Loaded Constraint plugin SkyCondition
2019-02-27 01:38:05.1: Loaded Constraint plugin TimeRange
2019-02-27 01:38:05.9: 35 plans are now pending.
2019-02-27 01:38:05.9: Attach ACP sequencer
2019-02-27 01:38:12.1: There is no controllable dome or roof.
2019-02-27 01:38:14.0: Sequencer does sky flats
2019-02-27 01:38:27.9: Weather successfully connected. Wait for valid data...
2019-02-27 01:38:35.1: ++ Weather Safe ++
2019-02-27 01:38:49.7: Dispatcher started at 27-Feb-2019 01:38:49 UTC
2019-02-27 01:45:26.8: ++ Observatory Startup ++
2019-02-27 01:45:26.8: Start ACP Sequencer's StartupObs script
2019-02-27 01:46:02.3: Open the observatory dome/roof
2019-02-27 01:47:09.9: Observatory startup succeeded, and dome opened successfully
2019-02-27 01:55:36.6: ++ Auto Focus ++
2019-02-27 01:55:36.6: Doing initial autofocus.
2019-02-27 01:55:36.6: Start special ACP AutoFocus script for scheduler
2019-02-27 02:35:39.9: ** ACP script "AutoFocusScheduler" failed:
2019-02-27 02:35:39.9: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 78 column 9.

2019-02-27 02:35:39.9: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-02-27 02:35:39.9: -- Weather Unsafe --
2019-02-27 03:36:22.4: ++ Weather Safe ++
2019-02-27 03:36:23.5: ++ Observatory Startup ++
2019-02-27 03:36:23.5: Start ACP Sequencer's StartupObs script
2019-02-27 03:36:43.3: Open the observatory dome/roof
2019-02-27 03:37:35.8: Observatory startup succeeded, and dome opened successfully
2019-02-27 03:37:35.8: ++ Auto Focus ++
2019-02-27 03:37:35.8: Doing initial autofocus.
2019-02-27 03:37:35.8: Start special ACP AutoFocus script for scheduler
2019-02-27 06:06:24.4: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-02-27 06:06:27.2: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan HX_Aur has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan FU_Cam has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan FY_Cam has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan HO_Cam has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan V0524_Cas has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan WZ_Per has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan V0603_Per has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.2: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-02-27 06:06:27.3: Rising plan delay enabled for Plan AL_Boo:
2019-02-27 06:06:27.3:     146 min. remaining to meet strict constraints.
2019-02-27 06:06:27.3:     380 min. remaining for the plan to fit before dawn.
2019-02-27 06:06:27.3:     deferred (rising) for 145 min, until 27-Feb-2019 08:31:27 UTC
2019-02-27 06:06:27.3: Rising plan delay enabled for Plan CV_Vir:
2019-02-27 06:06:27.3:     146 min. remaining to meet strict constraints.
2019-02-27 06:06:27.3:     380 min. remaining for the plan to fit before dawn.
2019-02-27 06:06:27.3:     deferred (rising) for 145 min, until 27-Feb-2019 08:31:27 UTC
2019-02-27 06:06:27.5: Rising plan delay enabled for Plan KK_UMa:
2019-02-27 06:06:27.5:     146 min. remaining to meet strict constraints.
2019-02-27 06:06:27.5:     380 min. remaining for the plan to fit before dawn.
2019-02-27 06:06:27.5:     but only 129 min till mid-Plan transit.
2019-02-27 06:06:27.5:     deferred (rising) for 127 min, until 27-Feb-2019 08:14:15 UTC
2019-02-27 06:06:27.5: Rising plan delay enabled for Plan RZ_Leo:
2019-02-27 06:06:27.5:     146 min. remaining to meet strict constraints.
2019-02-27 06:06:27.5:     320 min. remaining for the plan to fit before dawn.
2019-02-27 06:06:27.5:     but only 91 min till mid-Plan transit.
2019-02-27 06:06:27.5:     deferred (rising) for 84 min, until 27-Feb-2019 07:30:31 UTC
2019-02-27 06:06:27.5: Rising plan delay enabled for Plan SDSSJ121027+502735:
2019-02-27 06:06:27.5:     146 min. remaining to meet strict constraints.
2019-02-27 06:06:27.5:     384 min. remaining for the plan to fit before dawn.
2019-02-27 06:06:27.6:     deferred (rising) for 145 min, until 27-Feb-2019 08:31:52 UTC
2019-02-27 06:06:27.8: Dispatcher cycle time: 2.1 sec.
2019-02-27 06:06:27.8: Acquire data for Observation QZ_Aur...
2019-02-27 06:06:27.8:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-02-27 06:06:27.9: Send Observation QZ_Aur to ACP Sequencer
2019-02-27 06:07:27.5: ACP data acquisition failed for Observation QZ_Aur.
2019-02-27 06:07:27.5:   (Observation QZ_Aur interrupted by acquisition process failure.)
2019-02-27 06:07:29.2: Dispatcher cycle time: 0.3 sec.
2019-02-27 06:07:29.2: Acquire data for Observation M67...
2019-02-27 06:07:29.2:   (belongs to Project AAVSO_Monitoring, Plan M67)
2019-02-27 06:07:31.5: Send Observation M67 to ACP Sequencer
2019-02-27 06:18:03.6: ACP data acquisition failed for Observation M67.
2019-02-27 06:18:03.6:   (Observation M67 interrupted by acquisition process failure.)
2019-02-27 06:18:07.9: ++ Auto Focus ++
2019-02-27 06:18:08.0: Doing periodic autofocus.
2019-02-27 06:18:08.0: Start special ACP AutoFocus script for scheduler
2019-02-27 06:21:57.4: Next periodic autofocus ASAP after 27-Feb-2019 09:21:42 UTC
2019-02-27 06:21:59.8: Dispatcher cycle time: 1.2 sec.
2019-02-27 06:21:59.8: Acquire data for Observation SDSS073224...
2019-02-27 06:21:59.8:   (belongs to Project AGN, Plan SDSS073224)
2019-02-27 06:22:00.6: Send Observation SDSS073224 to ACP Sequencer
2019-02-27 06:26:16.8: ACP data acquisition failed for Observation SDSS073224.
2019-02-27 06:26:16.8:   (Observation SDSS073224 interrupted by acquisition process failure.)
2019-02-27 06:26:18.6: Dispatcher cycle time: 1.2 sec.
2019-02-27 06:26:18.6: Acquire data for Observation CY_UMa...
2019-02-27 06:26:18.6:   (belongs to Project Walker, Plan CY_UMa)
2019-02-27 06:26:18.6: Doing 20 sets
2019-02-27 06:26:18.9: Send Observation CY_UMa to ACP Sequencer
2019-02-27 07:15:41.6: Send Observation CY_UMa to ACP Sequencer
2019-02-27 08:00:59.6: Send Observation CY_UMa to ACP Sequencer
2019-02-27 08:59:48.8: Send Observation CY_UMa to ACP Sequencer
2019-02-27 10:06:43.6: Send Observation CY_UMa to ACP Sequencer
2019-02-27 11:07:40.6: Send Observation CY_UMa to ACP Sequencer
2019-02-27 11:52:55.6: Send Observation CY_UMa to ACP Sequencer
2019-02-27 12:38:11.9: ***Running Plan CY_UMa (best efforts) completed early:
2019-02-27 12:38:11.9:    Obs CY_UMa would extend past dawn
2019-02-27 12:38:11.9: Acquisition time: 22312.9512381 sec.
2019-02-27 12:38:11.9: Data for Observation CY_UMa of Plan CY_UMa acquired successfully.
2019-02-27 12:38:11.9: Plan CY_UMa completed successfully.
2019-02-27 12:38:11.9: Image Efficiency: 62.7%
2019-02-27 12:38:11.9: Cycle Efficiency: 100.0%
2019-02-27 12:38:14.4: ++ Auto Focus ++
2019-02-27 12:38:14.4: Doing periodic autofocus.
2019-02-27 12:38:14.4: Start special ACP AutoFocus script for scheduler
2019-02-27 12:44:23.7: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-02-27 12:58:24.4: ++ Dawn Flats ++
2019-02-27 12:58:24.4: Start ACP AutoFlat script for Dawn flats
2019-02-27 13:33:42.0: -- Observatory Shutdown --
2019-02-27 13:33:42.0: Start ACP Sequencer's ShutdownObs script
2019-02-27 13:38:05.5: Dispatcher stopped at 27-Feb-2019 13:38:05 UTC
2019-02-27 13:38:05.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-02-27 13:40:30.4: -- Weather Unsafe --
2019-02-28 01:35:33.0: ++ Weather Safe ++
2019-02-28 01:41:06.9: Dispatcher started at 28-Feb-2019 01:41:06 UTC
2019-02-28 01:41:07.4: Run statistics:
2019-02-28 01:41:07.4:   Observations:   54
2019-02-28 01:41:07.4:   Considered:     15
2019-02-28 01:41:07.4:   Completed:      1
2019-02-28 01:41:07.4:   Skipped:        8
2019-02-28 01:41:07.4:   Never Eligible: 39
2019-02-28 01:41:07.4:   Failed:         6
Log closed at Thu, Feb 28 2019 01:41:07 UTC (actual time)