Log opened at Tue, Feb 12 2019 01:59:34 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-02-12 01:59:35.1: ++ Observatory Startup ++
2019-02-12 01:59:35.1: Start ACP Sequencer's StartupObs script
2019-02-12 02:00:44.3: Open the observatory dome/roof
2019-02-12 02:01:51.9: Observatory startup succeeded, and dome opened successfully
2019-02-12 02:01:51.9: ++ Auto Focus ++
2019-02-12 02:01:51.9: Doing initial autofocus.
2019-02-12 02:01:51.9: Start special ACP AutoFocus script for scheduler
2019-02-12 02:06:28.5: Next periodic autofocus ASAP after 12-Feb-2019 05:06:05 UTC
2019-02-12 02:06:28.5: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan CB_32 has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan RZ_Leo has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan T_Ori has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan M67 has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan HQ_Aur has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan HX_Aur has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan AL_Boo has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan FU_Cam has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan FY_Cam has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan HO_Cam has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan V0524_Cas has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan DN_Lyn has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan WZ_Per has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan V0603_Per has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan V0336_Ser has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan KK_UMa has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.5: Plan CV_Vir has Monitor Mode. Time to resubmit it.
2019-02-12 02:06:28.6: Rising plan delay enabled for Plan CB_32:
2019-02-12 02:06:28.6:     283 min. remaining to meet strict constraints.
2019-02-12 02:06:28.6:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.6:     but only 69 min till mid-Plan transit.
2019-02-12 02:06:28.6:     deferred (rising) for 69 min, until 12-Feb-2019 03:15:57 UTC
2019-02-12 02:06:28.6: Rising plan delay enabled for Plan DN_Lyn:
2019-02-12 02:06:28.6:     501 min. remaining to meet strict constraints.
2019-02-12 02:06:28.6:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.6:     but only 184 min till mid-Plan transit.
2019-02-12 02:06:28.6:     deferred (rising) for 184 min, until 12-Feb-2019 05:10:50 UTC
2019-02-12 02:06:28.7: Rising plan delay enabled for Plan HI_Cam:
2019-02-12 02:06:28.7:     354 min. remaining to meet strict constraints.
2019-02-12 02:06:28.7:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.7:     but only 8 min till mid-Plan transit.
2019-02-12 02:06:28.7:     deferred (rising) for 8 min, until 12-Feb-2019 02:15:21 UTC
2019-02-12 02:06:28.7: Rising plan delay enabled for Plan HO_Cam:
2019-02-12 02:06:28.7:     384 min. remaining to meet strict constraints.
2019-02-12 02:06:28.7:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.7:     but only 45 min till mid-Plan transit.
2019-02-12 02:06:28.7:     deferred (rising) for 45 min, until 12-Feb-2019 02:52:24 UTC
2019-02-12 02:06:28.7: Rising plan delay enabled for Plan HQ_Aur:
2019-02-12 02:06:28.7:     387 min. remaining to meet strict constraints.
2019-02-12 02:06:28.7:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.7:     but only 62 min till mid-Plan transit.
2019-02-12 02:06:28.7:     deferred (rising) for 62 min, until 12-Feb-2019 03:09:23 UTC
2019-02-12 02:06:28.7: Rising plan delay enabled for Plan HX_Aur:
2019-02-12 02:06:28.7:     354 min. remaining to meet strict constraints.
2019-02-12 02:06:28.7:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.7:     but only 35 min till mid-Plan transit.
2019-02-12 02:06:28.7:     deferred (rising) for 35 min, until 12-Feb-2019 02:42:12 UTC
2019-02-12 02:06:28.7: Rising plan delay enabled for Plan MP_Gem:
2019-02-12 02:06:28.7:     406 min. remaining to meet strict constraints.
2019-02-12 02:06:28.7:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.7:     but only 141 min till mid-Plan transit.
2019-02-12 02:06:28.7:     deferred (rising) for 141 min, until 12-Feb-2019 04:27:47 UTC
2019-02-12 02:06:28.8: Rising plan delay enabled for Plan QZ_Aur:
2019-02-12 02:06:28.8:     353 min. remaining to meet strict constraints.
2019-02-12 02:06:28.8:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.8:     but only 61 min till mid-Plan transit.
2019-02-12 02:06:28.8:     deferred (rising) for 61 min, until 12-Feb-2019 03:08:01 UTC
2019-02-12 02:06:28.8: Rising plan delay enabled for Plan SDSS073224:
2019-02-12 02:06:28.8:     464 min. remaining to meet strict constraints.
2019-02-12 02:06:28.8:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.8:     but only 185 min till mid-Plan transit.
2019-02-12 02:06:28.8:     deferred (rising) for 185 min, until 12-Feb-2019 05:11:32 UTC
2019-02-12 02:06:28.8: Rising plan delay enabled for Plan T_Ori:
2019-02-12 02:06:28.8:     265 min. remaining to meet strict constraints.
2019-02-12 02:06:28.8:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.8:     but only 68 min till mid-Plan transit.
2019-02-12 02:06:28.8:     deferred (rising) for 68 min, until 12-Feb-2019 03:15:17 UTC
2019-02-12 02:06:28.8: Rising plan delay enabled for Plan V0959_Mon:
2019-02-12 02:06:28.8:     364 min. remaining to meet strict constraints.
2019-02-12 02:06:28.8:     651 min. remaining for the plan to fit before dawn.
2019-02-12 02:06:28.8:     but only 132 min till mid-Plan transit.
2019-02-12 02:06:28.8:     deferred (rising) for 132 min, until 12-Feb-2019 04:18:54 UTC
2019-02-12 02:06:29.0: Dispatcher cycle time: 0.6 sec.
2019-02-12 02:06:29.0: Acquire data for Observation AF_And...
2019-02-12 02:06:29.0:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2019-02-12 02:06:31.0: Send Observation AF_And to ACP Sequencer
2019-02-12 02:13:06.5: ACP data acquisition failed for Observation AF_And.
2019-02-12 02:13:06.5:   (Observation AF_And interrupted by acquisition process failure.)
2019-02-12 02:13:10.2: Dispatcher cycle time: 1.2 sec.
2019-02-12 02:13:10.2: Acquire data for Observation V0965_Per...
2019-02-12 02:13:10.2:   (belongs to Project Nova_Monitoring, Plan V0965_Per)
2019-02-12 02:13:10.9: Send Observation V0965_Per to ACP Sequencer
2019-02-12 02:15:46.0: ACP data acquisition failed for Observation V0965_Per.
2019-02-12 02:15:46.0:   (Observation V0965_Per interrupted by acquisition process failure.)
2019-02-12 02:15:46.7: Dispatcher cycle time: 0.2 sec.
2019-02-12 02:15:46.7: Acquire data for Observation GSC_3649-0825...
2019-02-12 02:15:46.7:   (belongs to Project Staels, Plan GSC_3649-0825)
2019-02-12 02:15:48.4: Send Observation GSC_3649-0825 to ACP Sequencer
2019-02-12 02:20:02.8: ACP data acquisition failed for Observation GSC_3649-0825.
2019-02-12 02:20:02.8:   (Observation GSC_3649-0825 interrupted by acquisition process failure.)
2019-02-12 02:20:06.4: Dispatcher cycle time: 0.2 sec.
2019-02-12 02:20:06.4: Acquire data for Observation HI_Cam...
2019-02-12 02:20:06.4:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HI_Cam)
2019-02-12 02:20:08.2: Send Observation HI_Cam to ACP Sequencer
2019-02-12 02:25:04.8: ACP data acquisition failed for Observation HI_Cam.
2019-02-12 02:25:04.8:   (Observation HI_Cam interrupted by acquisition process failure.)
2019-02-12 02:25:07.3: Dispatcher cycle time: 0.2 sec.
2019-02-12 02:25:07.3: Acquire data for Observation FW_Cam...
2019-02-12 02:25:07.3:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FW_Cam)
2019-02-12 02:25:08.1: Send Observation FW_Cam to ACP Sequencer
2019-02-12 02:28:45.4: ACP data acquisition failed for Observation FW_Cam.
2019-02-12 02:28:45.4:   (Observation FW_Cam interrupted by acquisition process failure.)
2019-02-12 02:28:48.5: Dispatcher cycle time: 0.2 sec.
2019-02-12 02:28:48.5: Acquire data for Observation FY_Cam...
2019-02-12 02:28:48.5:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FY_Cam)
2019-02-12 02:28:48.6: Send Observation FY_Cam to ACP Sequencer
2019-02-12 02:34:35.1: ACP data acquisition failed for Observation FY_Cam.
2019-02-12 02:34:35.1:   (Observation FY_Cam interrupted by acquisition process failure.)
2019-02-12 02:34:37.8: Rising plan delay enabled for Plan CY_UMa:
2019-02-12 02:34:37.8:     720 min. remaining to meet strict constraints.
2019-02-12 02:34:37.8:     623 min. remaining for the plan to fit before dawn.
2019-02-12 02:34:37.8:     but only 360 min till mid-Plan transit.
2019-02-12 02:34:37.8:     deferred (rising) for 360 min, until 12-Feb-2019 08:35:31 UTC
2019-02-12 02:34:37.9: Dispatcher cycle time: 0.9 sec.
2019-02-12 02:34:37.9: Acquire data for Observation FU_Cam...
2019-02-12 02:34:37.9:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FU_Cam)
2019-02-12 02:34:39.9: Send Observation FU_Cam to ACP Sequencer
2019-02-12 02:37:46.3: ACP data acquisition failed for Observation FU_Cam.
2019-02-12 02:37:46.3:   (Observation FU_Cam interrupted by acquisition process failure.)
2019-02-12 02:37:48.6: Dispatcher cycle time: 0.8 sec.
2019-02-12 02:37:48.6: Acquire data for Observation V0603_Per...
2019-02-12 02:37:48.6:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per)
2019-02-12 02:37:49.9: Send Observation V0603_Per to ACP Sequencer
2019-02-12 02:43:03.0: ACP data acquisition failed for Observation V0603_Per.
2019-02-12 02:43:03.0:   (Observation V0603_Per interrupted by acquisition process failure.)
2019-02-12 02:43:06.4: Dispatcher cycle time: 0.2 sec.
2019-02-12 02:43:06.4: Acquire data for Observation GSC_4030_204...
2019-02-12 02:43:06.4:   (belongs to Project Staels, Plan GSC_4030_204)
2019-02-12 02:43:06.4: Send Observation GSC_4030_204 to ACP Sequencer
2019-02-12 02:46:46.0: Acquisition time: 219.6603075 sec.
2019-02-12 02:46:46.0: Data for Observation GSC_4030_204 of Plan GSC_4030_204 acquired successfully.
2019-02-12 02:46:46.1: Plan GSC_4030_204 completed successfully.
2019-02-12 02:46:46.1: Image Efficiency: 13.6%
2019-02-12 02:46:46.1: Cycle Efficiency: 99.9%
2019-02-12 02:46:48.6: Dispatcher cycle time: 0.2 sec.
2019-02-12 02:46:48.6: Acquire data for Observation HX_Aur...
2019-02-12 02:46:48.6:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HX_Aur)
2019-02-12 02:46:48.6: Send Observation HX_Aur to ACP Sequencer
2019-02-12 02:51:25.9: ACP data acquisition failed for Observation HX_Aur.
2019-02-12 02:51:25.9:   (Observation HX_Aur interrupted by acquisition process failure.)
2019-02-12 02:51:29.5: Dispatcher cycle time: 0.1 sec.
2019-02-12 02:51:29.5: Acquire data for Observation WZ_Per...
2019-02-12 02:51:29.5:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan WZ_Per)
2019-02-12 02:51:29.5: Send Observation WZ_Per to ACP Sequencer
2019-02-12 02:55:39.1: ACP data acquisition failed for Observation WZ_Per.
2019-02-12 02:55:39.1:   (Observation WZ_Per interrupted by acquisition process failure.)
2019-02-12 02:55:41.1: Rising plan delay enabled for Plan M67:
2019-02-12 02:55:41.1:     432 min. remaining to meet strict constraints.
2019-02-12 02:55:41.1:     602 min. remaining for the plan to fit before dawn.
2019-02-12 02:55:41.1:     but only 214 min till mid-Plan transit.
2019-02-12 02:55:41.1:     deferred (rising) for 214 min, until 12-Feb-2019 06:30:12 UTC
2019-02-12 02:55:41.2: Dispatcher cycle time: 0.1 sec.
2019-02-12 02:55:41.2: Acquire data for Observation CM_Cas...
2019-02-12 02:55:41.2:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
2019-02-12 02:55:41.2: Send Observation CM_Cas to ACP Sequencer
2019-02-12 03:00:12.2: ACP data acquisition failed for Observation CM_Cas.
2019-02-12 03:00:12.2:   (Observation CM_Cas interrupted by acquisition process failure.)
2019-02-12 03:00:15.0: Dispatcher cycle time: 1.0 sec.
2019-02-12 03:00:15.0: Acquire data for Observation HO_Cam...
2019-02-12 03:00:15.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HO_Cam)
2019-02-12 03:00:17.1: Send Observation HO_Cam to ACP Sequencer
2019-02-12 03:06:09.5: ACP data acquisition failed for Observation HO_Cam.
2019-02-12 03:06:09.5:   (Observation HO_Cam interrupted by acquisition process failure.)
2019-02-12 03:06:11.8: Dispatcher cycle time: 0.6 sec.
2019-02-12 03:06:11.8: Acquire data for Observation V0524_Cas...
2019-02-12 03:06:11.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0524_Cas)
2019-02-12 03:06:12.3: Send Observation V0524_Cas to ACP Sequencer
2019-02-12 03:12:16.7: ACP data acquisition failed for Observation V0524_Cas.
2019-02-12 03:12:16.7:   (Observation V0524_Cas interrupted by acquisition process failure.)
2019-02-12 03:12:19.5: Dispatcher cycle time: 0.1 sec.
2019-02-12 03:12:19.5: Acquire data for Observation QZ_Aur...
2019-02-12 03:12:19.5:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-02-12 03:12:19.5: Send Observation QZ_Aur to ACP Sequencer
2019-02-12 06:44:19.5: ** ACP script "AcquireScheduler" failed:
2019-02-12 06:44:19.5: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1021 column 9.

2019-02-12 06:44:22.5: ACP OPERATOR ABORT: The operator killed the run
2019-02-12 06:44:22.5: ACP data acquisition failed for Observation QZ_Aur.
2019-02-12 06:44:22.5:   (Observation QZ_Aur interrupted by acquisition process failure.)
2019-02-12 06:44:24.5: -- Weather Unsafe --
2019-02-12 09:28:05.9: ++ Weather Safe ++
2019-02-12 09:28:07.5: ++ Observatory Startup ++
2019-02-12 09:28:07.8: Start ACP Sequencer's StartupObs script
2019-02-12 09:28:08.3: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:29:08.4: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:30:08.7: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:31:09.0: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:32:09.2: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:33:09.5: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:34:09.8: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:35:10.0: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:36:10.2: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:37:10.5: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:38:10.8: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:39:11.1: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:40:11.3: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:41:11.6: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:42:11.8: WARNING: Request sent while Weather Safety Script is running
2019-02-12 09:43:11.8: ERROR: Failed to start ACP script due to ACP being busy or locked
2019-02-12 09:45:02.6: -- Weather Unsafe --
2019-02-12 09:45:03.9: **EXCEPTION IN SCHEDULER:
2019-02-12 09:45:03.9: Observatory startup was interrupted by a weather unsafe event.
2019-02-12 09:45:03.9: Traceback:
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2019-02-12 09:45:09.0: Run statistics:
2019-02-12 09:45:09.0:   Observations:   54
2019-02-12 09:45:09.0:   Considered:     23
2019-02-12 09:45:09.0:   Completed:      1
2019-02-12 09:45:09.0:   Skipped:        5
2019-02-12 09:45:09.0:   Never Eligible: 31
2019-02-12 09:45:09.0:   Failed:         17
2019-02-12 09:45:09.0: Shutter-Open efficiency: 3.48%
2019-02-12 09:45:09.0: Overall Efficiency: 46.74%
2019-02-12 09:45:09.0: Release ACP sequencer
Log closed at Tue, Feb 12 2019 09:45:10 UTC (actual time)