Log opened at Mon, Jan 28 2019 23:35:24 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-01-29 01:08:31.5: ++ Weather Safe ++
2019-01-29 01:22:25.5: ++ Observatory Startup ++
2019-01-29 01:22:25.5: Start ACP Sequencer's StartupObs script
2019-01-29 01:23:31.9: Open the observatory dome/roof
2019-01-29 01:24:38.9: Observatory startup succeeded, and dome opened successfully
2019-01-29 01:32:07.7: ++ Auto Focus ++
2019-01-29 01:32:07.7: Doing initial autofocus.
2019-01-29 01:32:07.7: Start special ACP AutoFocus script for scheduler
2019-01-29 01:36:26.0: Next periodic autofocus ASAP after 29-Jan-2019 04:36:06 UTC
2019-01-29 01:36:26.1: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan CB_32 has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan RZ_Leo has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan T_Ori has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan M67 has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan FU_Cam has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan FW_Cam has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan FY_Cam has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan HI_Cam has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan HO_Cam has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan V0603_Per has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.1: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-01-29 01:36:26.2: Rising plan delay enabled for Plan CB_32:
2019-01-29 01:36:26.2:     368 min. remaining to meet strict constraints.
2019-01-29 01:36:26.2:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.2:     but only 154 min till mid-Plan transit.
2019-01-29 01:36:26.2:     deferred (rising) for 154 min, until 29-Jan-2019 04:11:00 UTC
2019-01-29 01:36:26.2: Rising plan delay enabled for Plan FU_Cam:
2019-01-29 01:36:26.2:     371 min. remaining to meet strict constraints.
2019-01-29 01:36:26.2:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.2:     but only 22 min till mid-Plan transit.
2019-01-29 01:36:26.2:     deferred (rising) for 22 min, until 29-Jan-2019 01:58:41 UTC
2019-01-29 01:36:26.2: Rising plan delay enabled for Plan FW_Cam:
2019-01-29 01:36:26.2:     384 min. remaining to meet strict constraints.
2019-01-29 01:36:26.2:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.2:     but only 29 min till mid-Plan transit.
2019-01-29 01:36:26.2:     deferred (rising) for 29 min, until 29-Jan-2019 02:05:30 UTC
2019-01-29 01:36:26.2: Rising plan delay enabled for Plan FY_Cam:
2019-01-29 01:36:26.2:     377 min. remaining to meet strict constraints.
2019-01-29 01:36:26.2:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.2:     but only 34 min till mid-Plan transit.
2019-01-29 01:36:26.2:     deferred (rising) for 34 min, until 29-Jan-2019 02:11:12 UTC
2019-01-29 01:36:26.3: Rising plan delay enabled for Plan HI_Cam:
2019-01-29 01:36:26.3:     428 min. remaining to meet strict constraints.
2019-01-29 01:36:26.3:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.3:     but only 93 min till mid-Plan transit.
2019-01-29 01:36:26.3:     deferred (rising) for 93 min, until 29-Jan-2019 03:10:24 UTC
2019-01-29 01:36:26.3: Rising plan delay enabled for Plan HO_Cam:
2019-01-29 01:36:26.3:     428 min. remaining to meet strict constraints.
2019-01-29 01:36:26.3:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.3:     but only 131 min till mid-Plan transit.
2019-01-29 01:36:26.3:     deferred (rising) for 131 min, until 29-Jan-2019 03:47:27 UTC
2019-01-29 01:36:26.3: Rising plan delay enabled for Plan MP_Gem:
2019-01-29 01:36:26.3:     428 min. remaining to meet strict constraints.
2019-01-29 01:36:26.3:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.3:     but only 226 min till mid-Plan transit.
2019-01-29 01:36:26.3:     deferred (rising) for 226 min, until 29-Jan-2019 05:22:50 UTC
2019-01-29 01:36:26.3: Rising plan delay enabled for Plan QZ_Aur:
2019-01-29 01:36:26.3:     438 min. remaining to meet strict constraints.
2019-01-29 01:36:26.3:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.3:     but only 146 min till mid-Plan transit.
2019-01-29 01:36:26.3:     deferred (rising) for 146 min, until 29-Jan-2019 04:03:04 UTC
2019-01-29 01:36:26.3: Rising plan delay enabled for Plan SDSS073224:
2019-01-29 01:36:26.3:     549 min. remaining to meet strict constraints.
2019-01-29 01:36:26.3:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.3:     but only 270 min till mid-Plan transit.
2019-01-29 01:36:26.3:     deferred (rising) for 270 min, until 29-Jan-2019 06:06:35 UTC
2019-01-29 01:36:26.4: Rising plan delay enabled for Plan T_Ori:
2019-01-29 01:36:26.4:     350 min. remaining to meet strict constraints.
2019-01-29 01:36:26.4:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.4:     but only 153 min till mid-Plan transit.
2019-01-29 01:36:26.4:     deferred (rising) for 153 min, until 29-Jan-2019 04:10:20 UTC
2019-01-29 01:36:26.4: Rising plan delay enabled for Plan V0603_Per:
2019-01-29 01:36:26.4:     369 min. remaining to meet strict constraints.
2019-01-29 01:36:26.4:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.4:     but only 46 min till mid-Plan transit.
2019-01-29 01:36:26.4:     deferred (rising) for 46 min, until 29-Jan-2019 02:22:32 UTC
2019-01-29 01:36:26.4: Rising plan delay enabled for Plan V0959_Mon:
2019-01-29 01:36:26.4:     428 min. remaining to meet strict constraints.
2019-01-29 01:36:26.4:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.4:     but only 217 min till mid-Plan transit.
2019-01-29 01:36:26.4:     deferred (rising) for 217 min, until 29-Jan-2019 05:13:57 UTC
2019-01-29 01:36:26.4: Rising plan delay enabled for Plan V0965_Per:
2019-01-29 01:36:26.4:     308 min. remaining to meet strict constraints.
2019-01-29 01:36:26.4:     691 min. remaining for the plan to fit before dawn.
2019-01-29 01:36:26.4:     but only 9 min till mid-Plan transit.
2019-01-29 01:36:26.4:     deferred (rising) for 9 min, until 29-Jan-2019 01:46:09 UTC
2019-01-29 01:36:26.6: Dispatcher cycle time: 0.5 sec.
2019-01-29 01:36:26.6: Acquire data for Observation AF_And...
2019-01-29 01:36:26.6:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2019-01-29 01:36:29.8: Send Observation AF_And to ACP Sequencer
2019-01-29 01:37:32.6: ** ACP script "AcquireScheduler" failed:
2019-01-29 01:37:32.6: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1111 column 17.

2019-01-29 01:37:35.6: ACP OPERATOR ABORT: The operator killed the run
2019-01-29 01:37:35.6: ACP data acquisition failed for Observation AF_And.
2019-01-29 01:37:35.6:   (Observation AF_And interrupted by acquisition process failure.)
2019-01-29 01:37:36.8: -- Weather Unsafe --
2019-01-29 04:21:13.1: ++ Weather Safe ++
2019-01-29 04:21:14.2: ++ Observatory Startup ++
2019-01-29 04:21:14.2: Start ACP Sequencer's StartupObs script
2019-01-29 04:21:32.5: Open the observatory dome/roof
2019-01-29 04:22:59.0: Observatory startup succeeded, and dome opened successfully
2019-01-29 04:22:59.4: ++ Auto Focus ++
2019-01-29 04:22:59.4: Doing initial autofocus.
2019-01-29 04:22:59.4: Start special ACP AutoFocus script for scheduler
2019-01-29 04:26:48.7: Next periodic autofocus ASAP after 29-Jan-2019 07:26:32 UTC
2019-01-29 04:26:48.8: Rising plan delay enabled for Plan CY_UMa:
2019-01-29 04:26:48.8:     666 min. remaining to meet strict constraints.
2019-01-29 04:26:48.8:     520 min. remaining for the plan to fit before dawn.
2019-01-29 04:26:48.8:     but only 303 min till mid-Plan transit.
2019-01-29 04:26:48.8:     deferred (rising) for 303 min, until 29-Jan-2019 09:30:34 UTC
2019-01-29 04:26:48.9: Rising plan delay enabled for Plan M67:
2019-01-29 04:26:48.9:     396 min. remaining to meet strict constraints.
2019-01-29 04:26:48.9:     520 min. remaining for the plan to fit before dawn.
2019-01-29 04:26:48.9:     but only 178 min till mid-Plan transit.
2019-01-29 04:26:48.9:     deferred (rising) for 178 min, until 29-Jan-2019 07:25:15 UTC
2019-01-29 04:26:49.1: Dispatcher cycle time: 0.4 sec.
2019-01-29 04:26:49.1: Acquire data for Observation QZ_Aur...
2019-01-29 04:26:49.1:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-01-29 04:26:51.5: Send Observation QZ_Aur to ACP Sequencer
2019-01-29 04:32:46.4: ACP data acquisition failed for Observation QZ_Aur.
2019-01-29 04:32:46.4:   (Observation QZ_Aur interrupted by acquisition process failure.)
2019-01-29 04:32:48.3: Dispatcher cycle time: 0.4 sec.
2019-01-29 04:32:48.3: Acquire data for Observation CB_32...
2019-01-29 04:32:48.3:   (belongs to Project Staels, Plan CB_32)
2019-01-29 04:32:50.2: Send Observation CB_32 to ACP Sequencer
2019-01-29 04:37:23.7: Acquisition time: 273.4503828 sec.
2019-01-29 04:37:23.7: Data for Observation CB_32 of Plan CB_32 acquired successfully.
2019-01-29 04:37:23.7: Plan CB_32 completed successfully.
2019-01-29 04:37:23.7: Image Efficiency: 32.9%
2019-01-29 04:37:23.7: Cycle Efficiency: 99.8%
2019-01-29 04:37:25.3: Dispatcher cycle time: 0.3 sec.
2019-01-29 04:37:25.3: Acquire data for Observation T_Ori...
2019-01-29 04:37:25.3:   (belongs to Project AAVSO_Monitoring, Plan T_Ori)
2019-01-29 04:37:25.3: Doing 3 sets
2019-01-29 04:37:26.7: Send Observation T_Ori to ACP Sequencer
2019-01-29 04:40:59.7: Send Observation T_Ori to ACP Sequencer
2019-01-29 04:44:12.7: Send Observation T_Ori to ACP Sequencer
2019-01-29 04:47:25.2: Acquisition time: 598.4408378 sec.
2019-01-29 04:47:25.2: Data for Observation T_Ori of Plan T_Ori acquired successfully.
2019-01-29 04:47:25.2: Plan T_Ori completed successfully.
2019-01-29 04:47:25.2: Image Efficiency: 45.1%
2019-01-29 04:47:25.2: Cycle Efficiency: 99.9%
2019-01-29 04:47:27.8: Dispatcher cycle time: 0.8 sec.
2019-01-29 04:47:27.8: Acquire data for Observation HO_Cam...
2019-01-29 04:47:27.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HO_Cam)
2019-01-29 04:47:29.0: Send Observation HO_Cam to ACP Sequencer
2019-01-29 04:52:52.6: ACP ERROR: Run failed for unknown reason (see ACP run log)
2019-01-29 04:52:52.6: ACP data acquisition failed for Observation HO_Cam.
2019-01-29 04:52:52.6:   (Observation HO_Cam interrupted by acquisition process failure.)
2019-01-29 04:52:54.7: -- Weather Unsafe --
2019-01-29 05:22:57.5: ++ Weather Safe ++
2019-01-29 05:22:58.6: ++ Observatory Startup ++
2019-01-29 05:22:58.6: Start ACP Sequencer's StartupObs script
2019-01-29 05:22:58.6: **EXCEPTION IN SCHEDULER:
2019-01-29 05:22:58.6: DOME FAILURE: Dome reports shutter error status
2019-01-29 05:22:58.6: Traceback:
   at DC3.Scheduler.ACPSequencer.get_DomeOpen()
   at DC3.Scheduler.ACPSequencer.DoStartup()
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2019-01-29 05:22:58.6: DOME/ROOF FAILURE: In addition, attempting to close your dome/roof this error occurred:
2019-01-29 05:22:58.6: DOME FAILURE: Dome reports shutter error status when asked to close.
2019-01-29 05:22:59.0: Run statistics:
2019-01-29 05:22:59.0:   Observations:   54
2019-01-29 05:22:59.0:   Considered:     33
2019-01-29 05:22:59.0:   Completed:      5
2019-01-29 05:22:59.0:   Skipped:        16
2019-01-29 05:22:59.0:   Never Eligible: 21
2019-01-29 05:22:59.0:   Failed:         12
2019-01-29 05:22:59.0: Shutter-Open efficiency: 46.56%
2019-01-29 05:22:59.0: Overall Efficiency: 99.90%
2019-01-29 05:22:59.0: Release ACP sequencer
Log closed at Tue, Jan 29 2019 05:23:00 UTC (actual time)