Log opened at Tue, Mar 19 2019 05:02:24 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-03-19 05:02:24.1: Internal clock initialized at 19-Mar-2019 05:02:24 UTC
2019-03-19 05:02:24.1: Loaded Constraint plugin AirMass
2019-03-19 05:02:24.1: Loaded Constraint plugin AirmassRange
2019-03-19 05:02:24.1: Loaded Constraint plugin Horizon
2019-03-19 05:02:24.1: Loaded Constraint plugin HourAngle
2019-03-19 05:02:24.1: Loaded Constraint plugin MoonAvoid
2019-03-19 05:02:24.1: Loaded Constraint plugin MoonDown
2019-03-19 05:02:24.1: Loaded Constraint plugin SkyCondition
2019-03-19 05:02:24.2: Loaded Constraint plugin TimeRange
2019-03-19 05:02:25.1: 39 plans are now pending.
2019-03-19 05:02:25.1: Attach ACP sequencer
2019-03-19 05:02:31.4: There is no controllable dome or roof.
2019-03-19 05:02:33.1: Sequencer does sky flats
2019-03-19 05:02:47.5: Weather successfully connected. Wait for valid data...
2019-03-19 05:02:57.4: ++ Weather Safe ++
2019-03-19 05:03:13.1: Dispatcher started at 19-Mar-2019 05:03:13 UTC
2019-03-19 05:03:13.3: ++ Observatory Startup ++
2019-03-19 05:03:13.4: Start ACP Sequencer's StartupObs script
2019-03-19 05:03:56.5: Open the observatory dome/roof
2019-03-19 05:05:15.5: Observatory startup succeeded, and dome opened successfully
2019-03-19 05:05:20.2: ++ Auto Focus ++
2019-03-19 05:05:20.2: Doing initial autofocus.
2019-03-19 05:05:20.2: Start special ACP AutoFocus script for scheduler
2019-03-19 05:06:19.5: Dispatcher stopped at 19-Mar-2019 05:06:19 UTC
2019-03-19 05:06:19.5: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-03-19 05:07:11.8: ** ACP script "AutoFocusScheduler" failed:
2019-03-19 05:07:11.8: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 78 column 9.

2019-03-19 05:07:11.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-03-19 05:08:53.6: Dispatcher started at 19-Mar-2019 05:08:53 UTC
2019-03-19 05:08:56.2: ++ Observatory Startup ++
2019-03-19 05:08:56.2: Start ACP Sequencer's StartupObs script
2019-03-19 05:08:56.2: Starting with open dome, unsafe weather will cause operator intervention error
2019-03-19 05:09:20.2: Observatory startup succeeded
2019-03-19 05:09:21.5: ++ Auto Focus ++
2019-03-19 05:09:21.5: Doing initial autofocus.
2019-03-19 05:09:21.5: Start special ACP AutoFocus script for scheduler
2019-03-19 05:13:49.8: Next periodic autofocus ASAP after 19-Mar-2019 08:13:30 UTC
2019-03-19 05:13:50.5: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan CB_32 has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan T_Ori has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan HQ_Aur has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan HX_Aur has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.6: Plan HO_Cam has Monitor Mode. Time to resubmit it.
2019-03-19 05:13:50.7: Rising plan delay enabled for Plan AL_Boo:
2019-03-19 05:13:50.7:     488 min. remaining to meet strict constraints.
2019-03-19 05:13:50.7:     408 min. remaining for the plan to fit before dawn.
2019-03-19 05:13:50.7:     but only 217 min till mid-Plan transit.
2019-03-19 05:13:50.7:     deferred (rising) for 215 min, until 19-Mar-2019 08:49:13 UTC
2019-03-19 05:13:50.7: Rising plan delay enabled for Plan CV_Vir:
2019-03-19 05:13:50.7:     406 min. remaining to meet strict constraints.
2019-03-19 05:13:50.7:     408 min. remaining for the plan to fit before dawn.
2019-03-19 05:13:50.7:     but only 149 min till mid-Plan transit.
2019-03-19 05:13:50.7:     deferred (rising) for 147 min, until 19-Mar-2019 07:41:27 UTC
2019-03-19 05:13:50.8: Rising plan delay enabled for Plan KK_UMa:
2019-03-19 05:13:50.8:     461 min. remaining to meet strict constraints.
2019-03-19 05:13:50.8:     408 min. remaining for the plan to fit before dawn.
2019-03-19 05:13:50.8:     but only 103 min till mid-Plan transit.
2019-03-19 05:13:50.8:     deferred (rising) for 101 min, until 19-Mar-2019 06:55:38 UTC
2019-03-19 05:13:50.9: Rising plan delay enabled for Plan RZ_Leo:
2019-03-19 05:13:50.9:     351 min. remaining to meet strict constraints.
2019-03-19 05:13:50.9:     348 min. remaining for the plan to fit before dawn.
2019-03-19 05:13:50.9:     but only 65 min till mid-Plan transit.
2019-03-19 05:13:50.9:     deferred (rising) for 58 min, until 19-Mar-2019 06:11:54 UTC
2019-03-19 05:13:51.1: Dispatcher cycle time: 1.4 sec.
2019-03-19 05:13:51.1: Acquire data for Observation QZ_Aur...
2019-03-19 05:13:51.1:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-03-19 05:13:51.5: Send Observation QZ_Aur to ACP Sequencer
2019-03-19 05:50:00.6: Acquisition time: 2169.0746098 sec.
2019-03-19 05:50:00.6: Data for Observation QZ_Aur of Plan QZ_Aur acquired successfully.
2019-03-19 05:50:00.6: Plan QZ_Aur completed successfully.
2019-03-19 05:50:00.6: Image Efficiency: 64.5%
2019-03-19 05:50:00.6: Cycle Efficiency: 99.9%
2019-03-19 05:50:04.7: Dispatcher cycle time: 0.2 sec.
2019-03-19 05:50:04.7: Acquire data for Observation M67...
2019-03-19 05:50:04.7:   (belongs to Project AAVSO_Monitoring, Plan M67)
2019-03-19 05:50:11.0: Send Observation M67 to ACP Sequencer
2019-03-19 06:24:37.3: Acquisition time: 2066.3328293 sec.
2019-03-19 06:24:37.3: Data for Observation M67 of Plan M67 acquired successfully.
2019-03-19 06:24:37.3: Plan M67 completed successfully.
2019-03-19 06:24:37.3: Image Efficiency: 68.2%
2019-03-19 06:24:37.3: Cycle Efficiency: 100.0%
2019-03-19 06:24:45.5: Dispatcher cycle time: 0.5 sec.
2019-03-19 06:24:45.5: Acquire data for Observation RZ_Leo...
2019-03-19 06:24:45.5:   (belongs to Project Walker, Plan RZ_Leo)
2019-03-19 06:24:47.0: Send Observation RZ_Leo to ACP Sequencer
2019-03-19 07:40:22.7: Acquisition time: 4535.6611664 sec.
2019-03-19 07:40:22.7: Data for Observation RZ_Leo of Plan RZ_Leo acquired successfully.
2019-03-19 07:40:22.7: Plan RZ_Leo completed successfully.
2019-03-19 07:40:22.7: Image Efficiency: 79.4%
2019-03-19 07:40:22.7: Cycle Efficiency: 100.0%
2019-03-19 07:40:27.4: Rising plan delay enabled for Plan Mkn_501:
2019-03-19 07:40:27.4:     576 min. remaining to meet strict constraints.
2019-03-19 07:40:27.4:     251 min. remaining for the plan to fit before dawn.
2019-03-19 07:40:27.4:     deferred (rising) for 248 min, until 19-Mar-2019 11:48:51 UTC
2019-03-19 07:40:27.4: Rising plan delay enabled for Plan V0336_Ser:
2019-03-19 07:40:27.4:     483 min. remaining to meet strict constraints.
2019-03-19 07:40:27.4:     261 min. remaining for the plan to fit before dawn.
2019-03-19 07:40:27.4:     but only 210 min till mid-Plan transit.
2019-03-19 07:40:27.4:     deferred (rising) for 208 min, until 19-Mar-2019 11:09:06 UTC
2019-03-19 07:40:27.5: Dispatcher cycle time: 0.1 sec.
2019-03-19 07:40:27.5: Acquire data for Observation CY_UMa...
2019-03-19 07:40:27.5:   (belongs to Project Walker, Plan CY_UMa)
2019-03-19 07:40:27.5: Doing 20 sets
2019-03-19 07:40:35.4: Send Observation CY_UMa to ACP Sequencer
2019-03-19 08:22:03.3: Send Observation CY_UMa to ACP Sequencer
2019-03-19 09:02:27.0: Send Observation CY_UMa to ACP Sequencer
2019-03-19 09:42:44.7: Send Observation CY_UMa to ACP Sequencer
2019-03-19 10:23:06.7: Send Observation CY_UMa to ACP Sequencer
2019-03-19 11:03:22.9: Send Observation CY_UMa to ACP Sequencer
2019-03-19 11:43:37.1: ***Running Plan CY_UMa (best efforts) completed early:
2019-03-19 11:43:37.1:    Obs CY_UMa would not meet strict constraints
2019-03-19 11:43:37.1: ***Running Plan CY_UMa (best efforts) completed early:
2019-03-19 11:43:37.1:    Obs CY_UMa would extend past dawn
2019-03-19 11:43:37.1: Acquisition time: 14581.6888114 sec.
2019-03-19 11:43:37.1: Data for Observation CY_UMa of Plan CY_UMa acquired successfully.
2019-03-19 11:43:37.1: Plan CY_UMa completed successfully.
2019-03-19 11:43:37.1: Image Efficiency: 82.3%
2019-03-19 11:43:37.1: Cycle Efficiency: 100.0%
2019-03-19 11:43:49.8: ++ Auto Focus ++
2019-03-19 11:43:49.8: Doing periodic autofocus.
2019-03-19 11:43:49.8: Start special ACP AutoFocus script for scheduler
2019-03-19 11:47:40.6: Next periodic autofocus ASAP after 19-Mar-2019 14:47:14 UTC
2019-03-19 11:47:43.4: Rising plan delay enabled for Plan 2MASS_J19395313+0557550:
2019-03-19 11:47:43.4:     423 min. remaining to meet strict constraints.
2019-03-19 11:47:43.4:     14 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.4:     deferred (rising) for 12 min, until 19-Mar-2019 12:00:35 UTC
2019-03-19 11:47:43.6: Rising plan delay enabled for Plan IRAS_19067+2158:
2019-03-19 11:47:43.6:     430 min. remaining to meet strict constraints.
2019-03-19 11:47:43.6:     14 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.6:     deferred (rising) for 12 min, until 19-Mar-2019 12:00:35 UTC
2019-03-19 11:47:43.6: Rising plan delay enabled for Plan IRAS_19183+2339:
2019-03-19 11:47:43.6:     445 min. remaining to meet strict constraints.
2019-03-19 11:47:43.6:     14 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.6:     deferred (rising) for 12 min, until 19-Mar-2019 12:00:35 UTC
2019-03-19 11:47:43.6: Rising plan delay enabled for Plan M56:
2019-03-19 11:47:43.6:     454 min. remaining to meet strict constraints.
2019-03-19 11:47:43.6:     13 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.6:     deferred (rising) for 11 min, until 19-Mar-2019 11:59:18 UTC
2019-03-19 11:47:43.6: Rising plan delay enabled for Plan NSVS_11201899:
2019-03-19 11:47:43.6:     431 min. remaining to meet strict constraints.
2019-03-19 11:47:43.6:     14 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.6:     deferred (rising) for 12 min, until 19-Mar-2019 12:00:35 UTC
2019-03-19 11:47:43.6: Rising plan delay enabled for Plan V1015_Her:
2019-03-19 11:47:43.6:     377 min. remaining to meet strict constraints.
2019-03-19 11:47:43.6:     14 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.6:     deferred (rising) for 12 min, until 19-Mar-2019 12:00:35 UTC
2019-03-19 11:47:43.7: Rising plan delay enabled for Plan V1723_Aql:
2019-03-19 11:47:43.7:     341 min. remaining to meet strict constraints.
2019-03-19 11:47:43.7:     10 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.7:     deferred (rising) for 8 min, until 19-Mar-2019 11:56:00 UTC
2019-03-19 11:47:43.7: Rising plan delay enabled for Plan V1724_Aql:
2019-03-19 11:47:43.7:     358 min. remaining to meet strict constraints.
2019-03-19 11:47:43.7:     10 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.7:     deferred (rising) for 8 min, until 19-Mar-2019 11:56:00 UTC
2019-03-19 11:47:43.7: Rising plan delay enabled for Plan V2481_Cyg:
2019-03-19 11:47:43.7:     575 min. remaining to meet strict constraints.
2019-03-19 11:47:43.7:     14 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.7:     deferred (rising) for 12 min, until 19-Mar-2019 12:00:35 UTC
2019-03-19 11:47:43.7: Rising plan delay enabled for Plan V339_Del_TMO61:
2019-03-19 11:47:43.7:     502 min. remaining to meet strict constraints.
2019-03-19 11:47:43.7:     13 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.7:     deferred (rising) for 12 min, until 19-Mar-2019 11:59:51 UTC
2019-03-19 11:47:43.7: Rising plan delay enabled for Plan V841_Oph:
2019-03-19 11:47:43.7:     197 min. remaining to meet strict constraints.
2019-03-19 11:47:43.7:     10 min. remaining for the plan to fit before dawn.
2019-03-19 11:47:43.7:     deferred (rising) for 8 min, until 19-Mar-2019 11:56:00 UTC
2019-03-19 11:47:43.7: Dispatcher cycle time: 1.3 sec.
2019-03-19 11:47:43.7: Acquire data for Observation V0336_Ser...
2019-03-19 11:47:43.7:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0336_Ser)
2019-03-19 11:47:49.3: Send Observation V0336_Ser to ACP Sequencer
2019-03-19 12:03:59.1: Acquisition time: 969.8069034 sec.
2019-03-19 12:03:59.1: Data for Observation V0336_Ser of Plan V0336_Ser acquired successfully.
2019-03-19 12:03:59.1: Plan V0336_Ser completed successfully.
2019-03-19 12:03:59.1: Image Efficiency: 40.2%
2019-03-19 12:03:59.1: Cycle Efficiency: 99.9%
2019-03-19 12:33:40.6: ++ Dawn Flats ++
2019-03-19 12:33:40.6: Start ACP AutoFlat script for Dawn flats
2019-03-19 13:01:57.9: ** ACP script "AutoFlat" failed:
2019-03-19 13:01:57.9: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 2307 column 5.

2019-03-19 13:02:00.2: -- Weather Unsafe --
2019-03-19 13:02:40.8: -- Observatory Shutdown --
2019-03-19 13:02:41.1: Start ACP Sequencer's ShutdownObs script
2019-03-19 13:02:41.9: WARNING: Request sent while Weather Safety Script is running
2019-03-19 19:17:38.0: Run statistics:
2019-03-19 19:17:38.0:   Observations:   54
2019-03-19 19:17:38.0:   Considered:     18
2019-03-19 19:17:38.0:   Completed:      5
2019-03-19 19:17:38.0:   Skipped:        10
2019-03-19 19:17:38.0:   Never Eligible: 36
2019-03-19 19:17:38.0:   Failed:         3
Log closed at Tue, Mar 19 2019 19:17:38 UTC (actual time)