Log opened at Sat, Jan 12 2019 03:45:03 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-01-12 03:45:04.0: ++ Observatory Startup ++
2019-01-12 03:45:04.0: Start ACP Sequencer's StartupObs script
2019-01-12 03:46:09.9: Open the observatory dome/roof
2019-01-12 03:47:18.6: Observatory startup succeeded, and dome opened successfully
2019-01-12 03:47:18.6: ++ Auto Focus ++
2019-01-12 03:47:18.6: Doing initial autofocus.
2019-01-12 03:47:18.6: Start special ACP AutoFocus script for scheduler
2019-01-12 03:51:36.6: Next periodic autofocus ASAP after 12-Jan-2019 06:51:20 UTC
2019-01-12 03:51:36.8: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan CB_32 has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan T_Ori has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan HQ_Aur has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan HX_Aur has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan FU_Cam has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan FY_Cam has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan HO_Cam has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan V0603_Per has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:36.8: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-01-12 03:51:37.9: Rising plan delay enabled for Plan CB_32:
2019-01-12 03:51:37.9:     300 min. remaining to meet strict constraints.
2019-01-12 03:51:37.9:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:37.9:     but only 86 min till mid-Plan transit.
2019-01-12 03:51:37.9:     deferred (rising) for 86 min, until 12-Jan-2019 05:17:50 UTC
2019-01-12 03:51:37.9: Rising plan delay enabled for Plan DN_Lyn:
2019-01-12 03:51:37.9:     518 min. remaining to meet strict constraints.
2019-01-12 03:51:37.9:     543 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:37.9:     but only 192 min till mid-Plan transit.
2019-01-12 03:51:37.9:     deferred (rising) for 190 min, until 12-Jan-2019 07:02:29 UTC
2019-01-12 03:51:37.9: Rising plan delay enabled for Plan HI_Cam:
2019-01-12 03:51:37.9:     371 min. remaining to meet strict constraints.
2019-01-12 03:51:37.9:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:37.9:     but only 25 min till mid-Plan transit.
2019-01-12 03:51:37.9:     deferred (rising) for 25 min, until 12-Jan-2019 04:17:15 UTC
2019-01-12 03:51:38.0: Rising plan delay enabled for Plan HO_Cam:
2019-01-12 03:51:38.0:     400 min. remaining to meet strict constraints.
2019-01-12 03:51:38.0:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.0:     but only 62 min till mid-Plan transit.
2019-01-12 03:51:38.0:     deferred (rising) for 62 min, until 12-Jan-2019 04:54:17 UTC
2019-01-12 03:51:38.0: Rising plan delay enabled for Plan HQ_Aur:
2019-01-12 03:51:38.0:     403 min. remaining to meet strict constraints.
2019-01-12 03:51:38.0:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.0:     but only 79 min till mid-Plan transit.
2019-01-12 03:51:38.0:     deferred (rising) for 79 min, until 12-Jan-2019 05:11:16 UTC
2019-01-12 03:51:38.0: Rising plan delay enabled for Plan HX_Aur:
2019-01-12 03:51:38.0:     371 min. remaining to meet strict constraints.
2019-01-12 03:51:38.0:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.0:     but only 52 min till mid-Plan transit.
2019-01-12 03:51:38.0:     deferred (rising) for 52 min, until 12-Jan-2019 04:44:06 UTC
2019-01-12 03:51:38.0: Rising plan delay enabled for Plan MP_Gem:
2019-01-12 03:51:38.0:     422 min. remaining to meet strict constraints.
2019-01-12 03:51:38.0:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.0:     but only 158 min till mid-Plan transit.
2019-01-12 03:51:38.0:     deferred (rising) for 158 min, until 12-Jan-2019 06:29:41 UTC
2019-01-12 03:51:38.0: Rising plan delay enabled for Plan QZ_Aur:
2019-01-12 03:51:38.0:     369 min. remaining to meet strict constraints.
2019-01-12 03:51:38.0:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.0:     but only 78 min till mid-Plan transit.
2019-01-12 03:51:38.0:     deferred (rising) for 78 min, until 12-Jan-2019 05:09:55 UTC
2019-01-12 03:51:38.1: Rising plan delay enabled for Plan SDSS073224:
2019-01-12 03:51:38.1:     481 min. remaining to meet strict constraints.
2019-01-12 03:51:38.1:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.1:     but only 201 min till mid-Plan transit.
2019-01-12 03:51:38.1:     deferred (rising) for 201 min, until 12-Jan-2019 07:13:25 UTC
2019-01-12 03:51:38.1: Rising plan delay enabled for Plan T_Ori:
2019-01-12 03:51:38.1:     281 min. remaining to meet strict constraints.
2019-01-12 03:51:38.1:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.1:     but only 85 min till mid-Plan transit.
2019-01-12 03:51:38.1:     deferred (rising) for 85 min, until 12-Jan-2019 05:17:10 UTC
2019-01-12 03:51:38.1: Rising plan delay enabled for Plan V0959_Mon:
2019-01-12 03:51:38.1:     381 min. remaining to meet strict constraints.
2019-01-12 03:51:38.1:     560 min. remaining for the plan to fit before dawn.
2019-01-12 03:51:38.1:     but only 149 min till mid-Plan transit.
2019-01-12 03:51:38.1:     deferred (rising) for 149 min, until 12-Jan-2019 06:20:48 UTC
2019-01-12 03:51:38.3: Dispatcher cycle time: 1.5 sec.
2019-01-12 03:51:38.3: Acquire data for Observation AF_And...
2019-01-12 03:51:38.3:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2019-01-12 03:51:38.4: Send Observation AF_And to ACP Sequencer
2019-01-12 04:23:27.4: Acquisition time: 1909.0126726 sec.
2019-01-12 04:23:27.4: Data for Observation AF_And of Plan AF_And acquired successfully.
2019-01-12 04:23:27.4: Plan AF_And completed successfully.
2019-01-12 04:23:27.4: Image Efficiency: 68.0%
2019-01-12 04:23:27.4: Cycle Efficiency: 99.9%
2019-01-12 04:23:31.2: Dispatcher cycle time: 0.4 sec.
2019-01-12 04:23:31.2: Acquire data for Observation V0965_Per...
2019-01-12 04:23:31.2:   (belongs to Project Nova_Monitoring, Plan V0965_Per)
2019-01-12 04:23:33.5: Send Observation V0965_Per to ACP Sequencer
2019-01-12 04:44:31.3: Acquisition time: 1257.821761 sec.
2019-01-12 04:44:31.3: Data for Observation V0965_Per of Plan V0965_Per acquired successfully.
2019-01-12 04:44:31.3: Plan V0965_Per completed successfully.
2019-01-12 04:44:31.3: Image Efficiency: 71.5%
2019-01-12 04:44:31.3: Cycle Efficiency: 100.0%
2019-01-12 04:44:33.6: Rising plan delay enabled for Plan CY_UMa:
2019-01-12 04:44:33.6:     715 min. remaining to meet strict constraints.
2019-01-12 04:44:33.6:     507 min. remaining for the plan to fit before dawn.
2019-01-12 04:44:33.6:     but only 352 min till mid-Plan transit.
2019-01-12 04:44:33.6:     deferred (rising) for 352 min, until 12-Jan-2019 10:37:24 UTC
2019-01-12 04:44:33.8: Dispatcher cycle time: 0.2 sec.
2019-01-12 04:44:33.8: Acquire data for Observation HI_Cam...
2019-01-12 04:44:33.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HI_Cam)
2019-01-12 04:44:35.6: Send Observation HI_Cam to ACP Sequencer
2019-01-12 05:00:08.4: Acquisition time: 932.7613059 sec.
2019-01-12 05:00:08.4: Data for Observation HI_Cam of Plan HI_Cam acquired successfully.
2019-01-12 05:00:08.4: Plan HI_Cam completed successfully.
2019-01-12 05:00:08.4: Image Efficiency: 41.8%
2019-01-12 05:00:08.4: Cycle Efficiency: 100.0%
2019-01-12 05:00:12.0: Rising plan delay enabled for Plan M67:
2019-01-12 05:00:12.0:     430 min. remaining to meet strict constraints.
2019-01-12 05:00:12.0:     457 min. remaining for the plan to fit before dawn.
2019-01-12 05:00:12.0:     but only 194 min till mid-Plan transit.
2019-01-12 05:00:12.0:     deferred (rising) for 191 min, until 12-Jan-2019 08:11:16 UTC
2019-01-12 05:00:12.1: Dispatcher cycle time: 0.8 sec.
2019-01-12 05:00:12.1: Acquire data for Observation HX_Aur...
2019-01-12 05:00:12.2:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HX_Aur)
2019-01-12 05:00:13.1: Send Observation HX_Aur to ACP Sequencer
2019-01-12 05:15:44.6: Acquisition time: 931.5913042 sec.
2019-01-12 05:15:44.6: Data for Observation HX_Aur of Plan HX_Aur acquired successfully.
2019-01-12 05:15:44.7: Plan HX_Aur completed successfully.
2019-01-12 05:15:44.7: Image Efficiency: 41.8%
2019-01-12 05:15:44.7: Cycle Efficiency: 99.9%
2019-01-12 05:15:47.7: Dispatcher cycle time: 1.6 sec.
2019-01-12 05:15:47.7: Acquire data for Observation QZ_Aur...
2019-01-12 05:15:47.7:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-01-12 05:15:48.2: Send Observation QZ_Aur to ACP Sequencer
2019-01-12 05:16:18.6: ** ACP script "AcquireScheduler" failed:
2019-01-12 05:16:18.6: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1021 column 9.

2019-01-12 05:16:21.6: ACP OPERATOR ABORT: The operator killed the run
2019-01-12 05:16:21.6: ACP data acquisition failed for Observation QZ_Aur.
2019-01-12 05:16:21.6:   (Observation QZ_Aur interrupted by acquisition process failure.)
2019-01-12 05:16:23.7: -- Weather Unsafe --
2019-01-12 08:34:25.0: ++ Weather Safe ++
2019-01-12 08:34:26.0: ++ Observatory Startup ++
2019-01-12 08:34:26.0: Start ACP Sequencer's StartupObs script
2019-01-12 08:34:45.5: Open the observatory dome/roof
2019-01-12 08:35:38.3: Observatory startup succeeded, and dome opened successfully
2019-01-12 08:35:38.3: ++ Auto Focus ++
2019-01-12 08:35:38.3: Doing initial autofocus.
2019-01-12 08:35:38.3: Start special ACP AutoFocus script for scheduler
2019-01-12 10:29:45.9: Next periodic autofocus ASAP after 12-Jan-2019 13:29:18 UTC
2019-01-12 10:29:47.9: Rising plan delay enabled for Plan AL_Boo:
2019-01-12 10:29:47.9:     431 min. remaining to meet strict constraints.
2019-01-12 10:29:47.9:     145 min. remaining for the plan to fit before dawn.
2019-01-12 10:29:47.9:     deferred (rising) for 143 min, until 12-Jan-2019 12:53:16 UTC
2019-01-12 10:29:48.0: Rising plan delay enabled for Plan CV_Vir:
2019-01-12 10:29:48.0:     349 min. remaining to meet strict constraints.
2019-01-12 10:29:48.0:     145 min. remaining for the plan to fit before dawn.
2019-01-12 10:29:48.0:     but only 92 min till mid-Plan transit.
2019-01-12 10:29:48.0:     deferred (rising) for 91 min, until 12-Jan-2019 12:00:56 UTC
2019-01-12 10:29:48.0: Rising plan delay enabled for Plan KK_UMa:
2019-01-12 10:29:48.0:     404 min. remaining to meet strict constraints.
2019-01-12 10:29:48.0:     145 min. remaining for the plan to fit before dawn.
2019-01-12 10:29:48.0:     but only 47 min till mid-Plan transit.
2019-01-12 10:29:48.0:     deferred (rising) for 45 min, until 12-Jan-2019 11:15:07 UTC
2019-01-12 10:29:48.0: Rising plan delay enabled for Plan RZ_Leo:
2019-01-12 10:29:48.0:     294 min. remaining to meet strict constraints.
2019-01-12 10:29:48.0:     85 min. remaining for the plan to fit before dawn.
2019-01-12 10:29:48.0:     but only 9 min till mid-Plan transit.
2019-01-12 10:29:48.0:     deferred (rising) for 1 min, until 12-Jan-2019 10:31:35 UTC
2019-01-12 10:29:48.1: Rising plan delay enabled for Plan SDSSJ121027+502735:
2019-01-12 10:29:48.1:     402 min. remaining to meet strict constraints.
2019-01-12 10:29:48.1:     149 min. remaining for the plan to fit before dawn.
2019-01-12 10:29:48.1:     but only 74 min till mid-Plan transit.
2019-01-12 10:29:48.1:     deferred (rising) for 73 min, until 12-Jan-2019 11:42:58 UTC
2019-01-12 10:29:48.1: Dispatcher cycle time: 1.3 sec.
2019-01-12 10:29:48.1: Acquire data for Observation M67...
2019-01-12 10:29:48.1:   (belongs to Project AAVSO_Monitoring, Plan M67)
2019-01-12 10:29:49.0: Send Observation M67 to ACP Sequencer
2019-01-12 11:02:43.8: Acquisition time: 1974.8327648 sec.
2019-01-12 11:02:43.8: Data for Observation M67 of Plan M67 acquired successfully.
2019-01-12 11:02:43.9: Plan M67 completed successfully.
2019-01-12 11:02:43.9: Image Efficiency: 71.4%
2019-01-12 11:02:43.9: Cycle Efficiency: 99.9%
2019-01-12 11:02:46.7: Dispatcher cycle time: 0.2 sec.
2019-01-12 11:02:46.7: Acquire data for Observation RZ_Leo...
2019-01-12 11:02:46.7:   (belongs to Project Walker, Plan RZ_Leo)
2019-01-12 11:02:46.8: Send Observation RZ_Leo to ACP Sequencer
2019-01-12 12:17:17.4: Acquisition time: 4470.6462589 sec.
2019-01-12 12:17:17.4: Data for Observation RZ_Leo of Plan RZ_Leo acquired successfully.
2019-01-12 12:17:17.4: Plan RZ_Leo completed successfully.
2019-01-12 12:17:17.4: Image Efficiency: 80.5%
2019-01-12 12:17:17.4: Cycle Efficiency: 100.0%
2019-01-12 12:17:22.0: Rising plan delay enabled for Plan Mkn_501:
2019-01-12 12:17:22.0:     559 min. remaining to meet strict constraints.
2019-01-12 12:17:22.0:     29 min. remaining for the plan to fit before dawn.
2019-01-12 12:17:22.0:     deferred (rising) for 27 min, until 12-Jan-2019 12:44:50 UTC
2019-01-12 12:17:22.0: Rising plan delay enabled for Plan V0336_Ser:
2019-01-12 12:17:22.0:     344 min. remaining to meet strict constraints.
2019-01-12 12:17:22.0:     37 min. remaining for the plan to fit before dawn.
2019-01-12 12:17:22.0:     deferred (rising) for 35 min, until 12-Jan-2019 12:53:16 UTC
2019-01-12 12:17:22.1: Dispatcher cycle time: 0.1 sec.
2019-01-12 12:17:22.1: Acquire data for Observation SDSSJ121027+502735...
2019-01-12 12:17:22.1:   (belongs to Project NMSU, Plan SDSSJ121027+502735)
2019-01-12 12:17:22.1: Send Observation SDSSJ121027+502735 to ACP Sequencer
2019-01-12 12:31:27.3: Acquisition time: 845.1711832 sec.
2019-01-12 12:31:27.3: Data for Observation SDSSJ121027+502735 of Plan SDSSJ121027+502735 acquired successfully.
2019-01-12 12:31:27.3: Plan SDSSJ121027+502735 completed successfully.
2019-01-12 12:31:27.3: Image Efficiency: 71.0%
2019-01-12 12:31:27.3: Cycle Efficiency: 100.0%
2019-01-12 12:31:30.4: Dispatcher cycle time: 0.9 sec.
2019-01-12 12:31:30.4: Acquire data for Observation CY_UMa...
2019-01-12 12:31:30.4:   (belongs to Project Walker, Plan CY_UMa)
2019-01-12 12:31:30.4: Doing 20 sets
2019-01-12 12:31:31.3: ***Running Plan CY_UMa (best efforts) completed early:
2019-01-12 12:31:31.3:    Obs CY_UMa would extend past dawn
2019-01-12 12:31:31.3: Acquisition time: 0 sec.
2019-01-12 12:31:31.3: Data for Observation CY_UMa of Plan CY_UMa acquired successfully.
2019-01-12 12:31:31.3: Plan CY_UMa completed successfully.
2019-01-12 12:31:31.3: Image Efficiency: 0.0%
2019-01-12 12:31:31.3: Cycle Efficiency: 0.0%
2019-01-12 12:31:31.5: Dispatcher cycle time: 0.1 sec.
2019-01-12 12:31:31.5: Acquire data for Observation CV_Vir...
2019-01-12 12:31:31.5:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CV_Vir)
2019-01-12 12:31:31.5: Send Observation CV_Vir to ACP Sequencer
2019-01-12 12:47:06.7: Acquisition time: 935.1713093 sec.
2019-01-12 12:47:06.7: Data for Observation CV_Vir of Plan CV_Vir acquired successfully.
2019-01-12 12:47:06.7: Plan CV_Vir completed successfully.
2019-01-12 12:47:06.7: Image Efficiency: 41.7%
2019-01-12 12:47:06.7: Cycle Efficiency: 100.0%
2019-01-12 12:47:08.6: Rising plan delay enabled for Plan Mkn_501:
2019-01-12 12:47:08.6:     529 min. remaining to meet strict constraints.
2019-01-12 12:47:08.6:     0 min. remaining for the plan to fit before dawn.
2019-01-12 12:47:08.6:     ending rising plan delay. Plan is now eligible.
2019-01-12 12:47:08.6: Rising plan delay enabled for Plan V1015_Her:
2019-01-12 12:47:08.6:     315 min. remaining to meet strict constraints.
2019-01-12 12:47:08.6:     7 min. remaining for the plan to fit before dawn.
2019-01-12 12:47:08.6:     deferred (rising) for 6 min, until 12-Jan-2019 12:53:16 UTC
2019-01-12 12:47:08.6: Dispatcher cycle time: 0.1 sec.
2019-01-12 12:47:08.6: Acquire data for Observation Mkn_501...
2019-01-12 12:47:08.6:   (belongs to Project AAVSO_SXAC_Mkn_501, Plan Mkn_501)
2019-01-12 12:47:08.6: Doing 3 sets
2019-01-12 12:47:08.7: Send Observation Mkn_501 to ACP Sequencer
2019-01-12 12:57:37.3: Send Observation Mkn_501 to ACP Sequencer
2019-01-12 13:04:55.4: Send Observation Mkn_501 to ACP Sequencer
2019-01-12 13:09:54.6: ACP data acquisition failed for Observation Mkn_501.
2019-01-12 13:09:54.6:   (Observation Mkn_501 interrupted by acquisition process failure.)
2019-01-12 13:27:33.6: ++ Dawn Flats ++
2019-01-12 13:27:33.7: Start ACP AutoFlat script for Dawn flats
2019-01-12 13:56:02.3: ** ACP script "AutoFlat" failed:
2019-01-12 13:56:02.3: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 1422 column 13.

2019-01-12 13:56:02.3: -- Weather Unsafe --
2019-01-12 13:56:38.0: -- Observatory Shutdown --
2019-01-12 13:56:38.4: Start ACP Sequencer's ShutdownObs script
2019-01-12 13:56:39.2: WARNING: Request sent while Weather Safety Script is running
2019-01-12 13:57:39.5: WARNING: Request sent while Weather Safety Script is running
2019-01-12 19:17:55.4: Run statistics:
2019-01-12 19:17:55.4:   Observations:   54
2019-01-12 19:17:55.4:   Considered:     35
2019-01-12 19:17:55.4:   Completed:      9
2019-01-12 19:17:55.4:   Skipped:        21
2019-01-12 19:17:55.4:   Never Eligible: 19
2019-01-12 19:17:55.4:   Failed:         5
Log closed at Sat, Jan 12 2019 19:17:55 UTC (actual time)