Log opened at Thu, Feb 07 2019 02:44:51 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-02-07 02:44:51.5: Internal clock initialized at 07-Feb-2019 02:44:51 UTC
2019-02-07 02:44:51.5: Loaded Constraint plugin AirMass
2019-02-07 02:44:51.5: Loaded Constraint plugin AirmassRange
2019-02-07 02:44:51.5: Loaded Constraint plugin Horizon
2019-02-07 02:44:51.5: Loaded Constraint plugin HourAngle
2019-02-07 02:44:51.5: Loaded Constraint plugin MoonAvoid
2019-02-07 02:44:51.5: Loaded Constraint plugin MoonDown
2019-02-07 02:44:51.5: Loaded Constraint plugin SkyCondition
2019-02-07 02:44:51.5: Loaded Constraint plugin TimeRange
2019-02-07 02:44:52.4: 17 plans are now pending.
2019-02-07 02:44:52.5: Attach ACP sequencer
2019-02-07 02:44:58.6: There is no controllable dome or roof.
2019-02-07 02:45:00.4: Sequencer does sky flats
2019-02-07 02:45:14.5: Weather successfully connected. Wait for valid data...
2019-02-07 02:45:51.5: Dispatcher started at 07-Feb-2019 02:45:51 UTC
2019-02-07 08:15:38.4: ++ Weather Safe ++
2019-02-07 08:15:39.4: ++ Observatory Startup ++
2019-02-07 08:15:39.4: Start ACP Sequencer's StartupObs script
2019-02-07 08:16:11.9: Open the observatory dome/roof
2019-02-07 08:17:20.9: Observatory startup succeeded, and dome opened successfully
2019-02-07 08:17:20.9: ++ Auto Focus ++
2019-02-07 08:17:20.9: Doing initial autofocus.
2019-02-07 08:17:20.9: Start special ACP AutoFocus script for scheduler
2019-02-07 08:22:41.5: ** ACP script "AutoFocusScheduler" failed:
2019-02-07 08:22:41.5: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 78 column 9.

2019-02-07 08:22:41.5: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-02-07 08:22:41.5: -- Weather Unsafe --
2019-02-07 08:59:21.2: ++ Weather Safe ++
2019-02-07 08:59:22.3: ++ Observatory Startup ++
2019-02-07 08:59:22.3: Start ACP Sequencer's StartupObs script
2019-02-07 08:59:42.3: Open the observatory dome/roof
2019-02-07 09:00:34.6: Observatory startup succeeded, and dome opened successfully
2019-02-07 09:00:34.6: ++ Auto Focus ++
2019-02-07 09:00:34.6: Doing initial autofocus.
2019-02-07 09:00:34.6: Start special ACP AutoFocus script for scheduler
2019-02-07 09:37:31.2: ** ACP script "AutoFocusScheduler" failed:
2019-02-07 09:37:31.2: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 77 column 9.

2019-02-07 09:37:31.2: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-02-07 09:37:31.3: -- Weather Unsafe --
2019-02-07 10:17:09.4: ++ Weather Safe ++
2019-02-07 10:17:10.5: ++ Observatory Startup ++
2019-02-07 10:17:10.5: Start ACP Sequencer's StartupObs script
2019-02-07 10:17:28.9: Open the observatory dome/roof
2019-02-07 10:18:22.9: Observatory startup succeeded, and dome opened successfully
2019-02-07 10:18:22.9: ++ Auto Focus ++
2019-02-07 10:18:22.9: Doing initial autofocus.
2019-02-07 10:18:22.9: Start special ACP AutoFocus script for scheduler
2019-02-07 10:35:12.8: ** ACP script "AutoFocusScheduler" failed:
2019-02-07 10:35:12.8: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 77 column 9.

2019-02-07 10:35:12.8: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2019-02-07 10:35:13.5: -- Weather Unsafe --
2019-02-07 11:37:29.8: ++ Weather Safe ++
2019-02-07 11:37:30.8: ++ Observatory Startup ++
2019-02-07 11:37:30.8: Start ACP Sequencer's StartupObs script
2019-02-07 11:37:51.8: Open the observatory dome/roof
2019-02-07 11:38:44.1: Observatory startup succeeded, and dome opened successfully
2019-02-07 11:38:44.1: ++ Auto Focus ++
2019-02-07 11:38:44.1: Doing initial autofocus.
2019-02-07 11:38:44.1: Start special ACP AutoFocus script for scheduler
2019-02-07 11:55:14.2: ** Autofocus failed (n=4), will retry ASAP after 15 min.
2019-02-07 11:55:14.5: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.5: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.5: Plan CB_32 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.5: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan RZ_Leo has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan T_Ori has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan V841_Oph has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan M67 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan HQ_Aur has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan HX_Aur has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan AL_Boo has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan FU_Cam has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan FY_Cam has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan HO_Cam has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan V0524_Cas has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan V1015_Her has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan DN_Lyn has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan WZ_Per has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan V0603_Per has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan KK_UMa has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan CV_Vir has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan Mkn_501 has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.6: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-02-07 11:55:14.8: Rising plan delay enabled for Plan Mkn_501:
2019-02-07 11:55:14.8:     479 min. remaining to meet strict constraints.
2019-02-07 11:55:14.8:     66 min. remaining for the plan to fit before dawn.
2019-02-07 11:55:14.8:     deferred (rising) for 66 min, until 07-Feb-2019 13:02:14 UTC
2019-02-07 11:55:14.9: Rising plan delay enabled for Plan V0336_Ser:
2019-02-07 11:55:14.9:     218 min. remaining to meet strict constraints.
2019-02-07 11:55:14.9:     49 min. remaining for the plan to fit before dawn.
2019-02-07 11:55:14.9:     deferred (rising) for 48 min, until 07-Feb-2019 12:43:29 UTC
2019-02-07 11:55:14.9: Rising plan delay enabled for Plan V1015_Her:
2019-02-07 11:55:14.9:     218 min. remaining to meet strict constraints.
2019-02-07 11:55:14.9:     66 min. remaining for the plan to fit before dawn.
2019-02-07 11:55:14.9:     deferred (rising) for 66 min, until 07-Feb-2019 13:02:14 UTC
2019-02-07 11:55:15.0: Dispatcher cycle time: 0.9 sec.
2019-02-07 11:55:15.0: Acquire data for Observation SDSSJ121027+502735...
2019-02-07 11:55:15.0:   (belongs to Project NMSU, Plan SDSSJ121027+502735)
2019-02-07 11:55:18.2: Send Observation SDSSJ121027+502735 to ACP Sequencer
2019-02-07 11:57:32.2: ACP data acquisition failed for Observation SDSSJ121027+502735.
2019-02-07 11:57:32.2:   (Observation SDSSJ121027+502735 interrupted by acquisition process failure.)
2019-02-07 11:57:34.7: Dispatcher cycle time: 0.2 sec.
2019-02-07 11:57:34.7: Acquire data for Observation AL_Boo...
2019-02-07 11:57:34.7:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan AL_Boo)
2019-02-07 11:57:34.7: Send Observation AL_Boo to ACP Sequencer
2019-02-07 12:14:39.5: Acquisition time: 1024.8114347 sec.
2019-02-07 12:14:39.5: Data for Observation AL_Boo of Plan AL_Boo acquired successfully.
2019-02-07 12:14:39.6: Plan AL_Boo completed successfully.
2019-02-07 12:14:39.6: Image Efficiency: 38.0%
2019-02-07 12:14:39.6: Cycle Efficiency: 100.0%
2019-02-07 12:14:44.2: ++ Auto Focus ++
2019-02-07 12:14:44.2: Doing periodic autofocus.
2019-02-07 12:14:44.2: Start special ACP AutoFocus script for scheduler
2019-02-07 12:32:41.8: ** Autofocus failed (n=5), will retry ASAP after 20 min.
2019-02-07 12:32:43.3: Rising plan delay enabled for Plan M56:
2019-02-07 12:32:43.3:     180 min. remaining to meet strict constraints.
2019-02-07 12:32:43.3:     11 min. remaining for the plan to fit before dawn.
2019-02-07 12:32:43.3:     deferred (rising) for 9 min, until 07-Feb-2019 12:42:12 UTC
2019-02-07 12:32:43.3: Rising plan delay enabled for Plan V841_Oph:
2019-02-07 12:32:43.3:     180 min. remaining to meet strict constraints.
2019-02-07 12:32:43.3:     29 min. remaining for the plan to fit before dawn.
2019-02-07 12:32:43.3:     deferred (rising) for 29 min, until 07-Feb-2019 13:02:14 UTC
2019-02-07 12:32:43.4: Dispatcher cycle time: 1.1 sec.
2019-02-07 12:32:43.4: Acquire data for Observation CY_UMa...
2019-02-07 12:32:43.4:   (belongs to Project Walker, Plan CY_UMa)
2019-02-07 12:32:43.4: Doing 20 sets
2019-02-07 12:32:43.4: Open the observatory dome/roof
2019-02-07 12:33:38.7: ++ Auto Focus ++
2019-02-07 12:33:38.8: Doing initial autofocus.
2019-02-07 12:33:38.8: Start special ACP AutoFocus script for scheduler
2019-02-07 12:38:08.2: ** Autofocus failed (n=6), will retry ASAP after 20 min.
2019-02-07 12:38:08.2: ***Running Plan CY_UMa (best efforts) completed early:
2019-02-07 12:38:08.2:    Obs CY_UMa would extend past dawn
2019-02-07 12:38:08.2: Acquisition time: 0 sec.
2019-02-07 12:38:08.2: Data for Observation CY_UMa of Plan CY_UMa acquired successfully.
2019-02-07 12:38:08.2: Plan CY_UMa completed successfully.
2019-02-07 12:38:08.2: Image Efficiency: 0.0%
2019-02-07 12:38:08.2: Cycle Efficiency: 0.0%
2019-02-07 12:38:11.5: Rising plan delay enabled for Plan IRAS_19067+2158:
2019-02-07 12:38:11.5:     175 min. remaining to meet strict constraints.
2019-02-07 12:38:11.5:     7 min. remaining for the plan to fit before dawn.
2019-02-07 12:38:11.5:     deferred (rising) for 5 min, until 07-Feb-2019 12:43:29 UTC
2019-02-07 12:38:11.5: Rising plan delay enabled for Plan NSVS_11201899:
2019-02-07 12:38:11.5:     175 min. remaining to meet strict constraints.
2019-02-07 12:38:11.5:     7 min. remaining for the plan to fit before dawn.
2019-02-07 12:38:11.5:     deferred (rising) for 5 min, until 07-Feb-2019 12:43:29 UTC
2019-02-07 12:38:11.6: Dispatcher cycle time: 0.1 sec.
2019-02-07 12:38:11.6: Acquire data for Observation KK_UMa...
2019-02-07 12:38:11.6:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan KK_UMa)
2019-02-07 12:38:11.6: Send Observation KK_UMa to ACP Sequencer
2019-02-07 12:54:54.3: Acquisition time: 1002.7514038 sec.
2019-02-07 12:54:54.3: Data for Observation KK_UMa of Plan KK_UMa acquired successfully.
2019-02-07 12:54:54.4: Plan KK_UMa completed successfully.
2019-02-07 12:54:54.4: Image Efficiency: 38.9%
2019-02-07 12:54:54.4: Cycle Efficiency: 100.0%
2019-02-07 12:58:17.4: ++ Auto Focus ++
2019-02-07 12:58:17.4: Doing periodic autofocus.
2019-02-07 12:58:17.4: Start special ACP AutoFocus script for scheduler
2019-02-07 13:06:00.1: ** ACP script "AutoFocusScheduler" failed:
2019-02-07 13:06:00.2: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 81 column 2.

2019-02-07 13:06:00.2: ** Autofocus failed (n=7), will retry ASAP after 20 min.
2019-02-07 13:06:00.4: -- Weather Unsafe --
2019-02-07 13:16:52.7: Skipping dawn sky flats because the weather was unsafe at sky-flat time
2019-02-07 13:17:22.8: -- Observatory Shutdown --
2019-02-07 13:17:22.8: Start ACP Sequencer's ShutdownObs script
2019-02-07 13:43:31.3: Dispatcher stopped at 07-Feb-2019 13:43:31 UTC
2019-02-07 13:43:31.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-02-08 01:28:38.6: ++ Weather Safe ++
2019-02-08 02:03:56.6: Dispatcher started at 08-Feb-2019 02:03:56 UTC
2019-02-08 02:03:57.8: Run statistics:
2019-02-08 02:03:57.8:   Observations:   54
2019-02-08 02:03:57.8:   Considered:     9
2019-02-08 02:03:57.8:   Completed:      3
2019-02-08 02:03:57.8:   Skipped:        2
2019-02-08 02:03:57.8:   Never Eligible: 45
2019-02-08 02:03:57.8:   Failed:         4
Log closed at Fri, Feb 08 2019 02:03:57 UTC (actual time)