Log opened at Thu, Sep 13 2018 19:05:30 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2018-09-14 01:40:50.8: ++ Weather Safe ++
2018-09-14 01:59:15.7: ++ Observatory Startup ++
2018-09-14 01:59:15.7: Start ACP Sequencer's StartupObs script
2018-09-14 02:00:16.8: Open the observatory dome/roof
2018-09-14 02:01:29.0: Observatory startup succeeded, and dome opened successfully
2018-09-14 02:09:20.9: ++ Auto Focus ++
2018-09-14 02:09:20.9: Doing initial autofocus.
2018-09-14 02:09:20.9: Start special ACP AutoFocus script for scheduler
2018-09-14 02:13:42.3: Next periodic autofocus ASAP after 14-Sep-2018 05:13:22 UTC
2018-09-14 02:13:44.9: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan CB_32 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan V0809_Cep has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan V1724_Aql has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan V1723_Aql has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan V1722_Aql has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan V1721_Aql has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:44.9: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan M56 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan U_Aqr has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan V841_Oph has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan Mkn_501 has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.0: Plan AF_And has Monitor Mode. Time to resubmit it.
2018-09-14 02:13:45.7: Rising plan delay enabled for Plan 2MASS_J19395313+0557550:
2018-09-14 02:13:45.7:     293 min. remaining to meet strict constraints.
2018-09-14 02:13:45.7:     566 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:45.7:     but only 52 min till mid-Plan transit.
2018-09-14 02:13:45.7:     deferred (rising) for 50 min, until 14-Sep-2018 03:04:26 UTC
2018-09-14 02:13:46.0: Rising plan delay enabled for Plan GSC_3649-0825:
2018-09-14 02:13:46.0:     612 min. remaining to meet strict constraints.
2018-09-14 02:13:46.0:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.0:     but only 287 min till mid-Plan transit.
2018-09-14 02:13:46.0:     deferred (rising) for 287 min, until 14-Sep-2018 07:01:10 UTC
2018-09-14 02:13:46.1: Rising plan delay enabled for Plan IRAS_19067+2158:
2018-09-14 02:13:46.1:     299 min. remaining to meet strict constraints.
2018-09-14 02:13:46.1:     565 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.1:     but only 21 min till mid-Plan transit.
2018-09-14 02:13:46.1:     deferred (rising) for 19 min, until 14-Sep-2018 02:33:31 UTC
2018-09-14 02:13:46.1: Rising plan delay enabled for Plan IRAS_19183+2339:
2018-09-14 02:13:46.1:     314 min. remaining to meet strict constraints.
2018-09-14 02:13:46.1:     565 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.1:     but only 33 min till mid-Plan transit.
2018-09-14 02:13:46.1:     deferred (rising) for 31 min, until 14-Sep-2018 02:45:02 UTC
2018-09-14 02:13:46.1: Rising plan delay enabled for Plan M56:
2018-09-14 02:13:46.1:     323 min. remaining to meet strict constraints.
2018-09-14 02:13:46.1:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.1:     but only 37 min till mid-Plan transit.
2018-09-14 02:13:46.1:     deferred (rising) for 37 min, until 14-Sep-2018 02:51:25 UTC
2018-09-14 02:13:46.1: Rising plan delay enabled for Plan NGC_7790:
2018-09-14 02:13:46.1:     660 min. remaining to meet strict constraints.
2018-09-14 02:13:46.1:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.1:     but only 318 min till mid-Plan transit.
2018-09-14 02:13:46.1:     deferred (rising) for 318 min, until 14-Sep-2018 07:32:27 UTC
2018-09-14 02:13:46.1: Rising plan delay enabled for Plan NSVS_11201899:
2018-09-14 02:13:46.1:     300 min. remaining to meet strict constraints.
2018-09-14 02:13:46.1:     565 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.1:     but only 22 min till mid-Plan transit.
2018-09-14 02:13:46.1:     deferred (rising) for 20 min, until 14-Sep-2018 02:34:26 UTC
2018-09-14 02:13:46.2: Rising plan delay enabled for Plan V0809_Cep:
2018-09-14 02:13:46.2:     609 min. remaining to meet strict constraints.
2018-09-14 02:13:46.2:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.2:     but only 268 min till mid-Plan transit.
2018-09-14 02:13:46.2:     deferred (rising) for 268 min, until 14-Sep-2018 06:42:16 UTC
2018-09-14 02:13:46.2: Rising plan delay enabled for Plan V1721_Aql:
2018-09-14 02:13:46.2:     262 min. remaining to meet strict constraints.
2018-09-14 02:13:46.2:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.2:     but only 27 min till mid-Plan transit.
2018-09-14 02:13:46.2:     deferred (rising) for 27 min, until 14-Sep-2018 02:41:20 UTC
2018-09-14 02:13:46.2: Rising plan delay enabled for Plan V1722_Aql:
2018-09-14 02:13:46.2:     290 min. remaining to meet strict constraints.
2018-09-14 02:13:46.2:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.2:     but only 35 min till mid-Plan transit.
2018-09-14 02:13:46.2:     deferred (rising) for 35 min, until 14-Sep-2018 02:49:00 UTC
2018-09-14 02:13:46.2: Rising plan delay enabled for Plan V1723_Aql:
2018-09-14 02:13:46.2:     211 min. remaining to meet strict constraints.
2018-09-14 02:13:46.2:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.2:     but only 8 min till mid-Plan transit.
2018-09-14 02:13:46.2:     deferred (rising) for 8 min, until 14-Sep-2018 02:22:33 UTC
2018-09-14 02:13:46.2: Rising plan delay enabled for Plan V1724_Aql:
2018-09-14 02:13:46.2:     227 min. remaining to meet strict constraints.
2018-09-14 02:13:46.2:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.2:     but only 13 min till mid-Plan transit.
2018-09-14 02:13:46.2:     deferred (rising) for 13 min, until 14-Sep-2018 02:27:28 UTC
2018-09-14 02:13:46.3: Rising plan delay enabled for Plan V339_Del_TMO61:
2018-09-14 02:13:46.3:     371 min. remaining to meet strict constraints.
2018-09-14 02:13:46.3:     583 min. remaining for the plan to fit before dawn.
2018-09-14 02:13:46.3:     but only 104 min till mid-Plan transit.
2018-09-14 02:13:46.3:     deferred (rising) for 104 min, until 14-Sep-2018 03:58:09 UTC
2018-09-14 02:13:46.3: Dispatcher cycle time: 1.4 sec.
2018-09-14 02:13:46.3: Acquire data for Observation Mkn_501...
2018-09-14 02:13:46.3:   (belongs to Project AAVSO_SXAC_Mkn_501, Plan Mkn_501)
2018-09-14 02:13:46.3: Doing 3 sets
2018-09-14 02:13:46.3: Send Observation Mkn_501 to ACP Sequencer
2018-09-14 02:21:42.3: Send Observation Mkn_501 to ACP Sequencer
2018-09-14 02:29:27.1: Send Observation Mkn_501 to ACP Sequencer
2018-09-14 02:37:01.9: Acquisition time: 1395.5160511 sec.
2018-09-14 02:37:01.9: Data for Observation Mkn_501 of Plan Mkn_501 acquired successfully.
2018-09-14 02:37:01.9: Plan Mkn_501 completed successfully.
2018-09-14 02:37:01.9: Image Efficiency: 64.4%
2018-09-14 02:37:01.9: Cycle Efficiency: 99.9%
2018-09-14 02:37:07.4: Dispatcher cycle time: 0.2 sec.
2018-09-14 02:37:07.4: Acquire data for Observation V841_Oph...
2018-09-14 02:37:07.4:   (belongs to Project AAVSO_Monitoring, Plan V841_Oph)
2018-09-14 02:37:11.1: Send Observation V841_Oph to ACP Sequencer
2018-09-14 02:57:59.9: Acquisition time: 1248.8601935 sec.
2018-09-14 02:57:59.9: Data for Observation V841_Oph of Plan V841_Oph acquired successfully.
2018-09-14 02:58:00.0: Plan V841_Oph completed successfully.
2018-09-14 02:58:00.0: Image Efficiency: 64.0%
2018-09-14 02:58:00.0: Cycle Efficiency: 100.0%
2018-09-14 02:58:09.1: Dispatcher cycle time: 0.6 sec.
2018-09-14 02:58:09.1: Acquire data for Observation V1724_Aql...
2018-09-14 02:58:09.1:   (belongs to Project Nova_Monitoring, Plan V1724_Aql)
2018-09-14 02:58:12.0: Send Observation V1724_Aql to ACP Sequencer
2018-09-14 03:18:44.5: Acquisition time: 1232.5269648 sec.
2018-09-14 03:18:44.5: Data for Observation V1724_Aql of Plan V1724_Aql acquired successfully.
2018-09-14 03:18:44.5: Plan V1724_Aql completed successfully.
2018-09-14 03:18:44.5: Image Efficiency: 73.0%
2018-09-14 03:18:44.5: Cycle Efficiency: 100.0%
2018-09-14 03:18:49.2: Rising plan delay enabled for Plan AF_And:
2018-09-14 03:18:49.2:     604 min. remaining to meet strict constraints.
2018-09-14 03:18:49.2:     517 min. remaining for the plan to fit before dawn.
2018-09-14 03:18:49.2:     but only 298 min till mid-Plan transit.
2018-09-14 03:18:49.2:     deferred (rising) for 298 min, until 14-Sep-2018 08:17:29 UTC
2018-09-14 03:18:49.3: Rising plan delay enabled for Plan U_Aqr:
2018-09-14 03:18:49.3:     284 min. remaining to meet strict constraints.
2018-09-14 03:18:49.3:     517 min. remaining for the plan to fit before dawn.
2018-09-14 03:18:49.3:     but only 138 min till mid-Plan transit.
2018-09-14 03:18:49.3:     deferred (rising) for 138 min, until 14-Sep-2018 05:37:42 UTC
2018-09-14 03:18:49.4: Dispatcher cycle time: 0.3 sec.
2018-09-14 03:18:49.4: Acquire data for Observation V1722_Aql...
2018-09-14 03:18:49.4:   (belongs to Project Nova_Monitoring, Plan V1722_Aql)
2018-09-14 03:18:49.8: Send Observation V1722_Aql to ACP Sequencer
2018-09-14 03:51:48.4: Acquisition time: 1978.6606754 sec.
2018-09-14 03:51:48.4: Data for Observation V1722_Aql of Plan V1722_Aql acquired successfully.
2018-09-14 03:51:48.4: Plan V1722_Aql completed successfully.
2018-09-14 03:51:48.4: Image Efficiency: 75.8%
2018-09-14 03:51:48.4: Cycle Efficiency: 100.0%
2018-09-14 03:51:52.4: Dispatcher cycle time: 0.1 sec.
2018-09-14 03:51:52.4: Acquire data for Observation V1721_Aql...
2018-09-14 03:51:52.4:   (belongs to Project Nova_Monitoring, Plan V1721_Aql)
2018-09-14 03:51:52.5: Send Observation V1721_Aql to ACP Sequencer
2018-09-14 04:24:11.7: Acquisition time: 1939.2082061 sec.
2018-09-14 04:24:11.7: Data for Observation V1721_Aql of Plan V1721_Aql acquired successfully.
2018-09-14 04:24:11.7: Plan V1721_Aql completed successfully.
2018-09-14 04:24:11.7: Image Efficiency: 77.3%
2018-09-14 04:24:11.7: Cycle Efficiency: 100.0%
2018-09-14 04:24:17.5: Dispatcher cycle time: 0.5 sec.
2018-09-14 04:24:17.5: Acquire data for Observation V1723_Aql...
2018-09-14 04:24:17.5:   (belongs to Project Nova_Monitoring, Plan V1723_Aql)
2018-09-14 04:24:25.2: Send Observation V1723_Aql to ACP Sequencer
2018-09-14 04:42:21.2: ACP ERROR: Run failed for unknown reason (see ACP run log)
2018-09-14 04:42:21.2: ACP data acquisition failed for Observation V1723_Aql.
2018-09-14 04:42:21.2:   (Observation V1723_Aql interrupted by acquisition process failure.)
2018-09-14 04:42:25.4: -- Weather Unsafe --
2018-09-14 05:49:50.7: ++ Weather Safe ++
2018-09-14 05:49:51.7: ++ Observatory Startup ++
2018-09-14 05:49:51.7: Start ACP Sequencer's StartupObs script
2018-09-14 05:50:06.9: Open the observatory dome/roof
2018-09-14 05:51:20.7: Observatory startup succeeded, and dome opened successfully
2018-09-14 05:51:23.4: ++ Auto Focus ++
2018-09-14 05:51:23.5: Doing initial autofocus.
2018-09-14 05:51:23.5: Start special ACP AutoFocus script for scheduler
2018-09-14 05:55:33.1: Next periodic autofocus ASAP after 14-Sep-2018 08:55:14 UTC
2018-09-14 05:55:36.7: Rising plan delay enabled for Plan FU_Cam:
2018-09-14 05:55:36.7:     651 min. remaining to meet strict constraints.
2018-09-14 05:55:36.7:     344 min. remaining for the plan to fit before dawn.
2018-09-14 05:55:36.7:     but only 293 min till mid-Plan transit.
2018-09-14 05:55:36.7:     deferred (rising) for 291 min, until 14-Sep-2018 10:47:06 UTC
2018-09-14 05:55:36.7: Rising plan delay enabled for Plan FW_Cam:
2018-09-14 05:55:36.7:     663 min. remaining to meet strict constraints.
2018-09-14 05:55:36.7:     344 min. remaining for the plan to fit before dawn.
2018-09-14 05:55:36.7:     but only 300 min till mid-Plan transit.
2018-09-14 05:55:36.7:     deferred (rising) for 298 min, until 14-Sep-2018 10:53:56 UTC
2018-09-14 05:55:36.7: Rising plan delay enabled for Plan FY_Cam:
2018-09-14 05:55:36.7:     656 min. remaining to meet strict constraints.
2018-09-14 05:55:36.7:     344 min. remaining for the plan to fit before dawn.
2018-09-14 05:55:36.7:     but only 305 min till mid-Plan transit.
2018-09-14 05:55:36.7:     deferred (rising) for 304 min, until 14-Sep-2018 10:59:37 UTC
2018-09-14 05:55:36.7: Rising plan delay enabled for Plan V0965_Per:
2018-09-14 05:55:36.7:     587 min. remaining to meet strict constraints.
2018-09-14 05:55:36.7:     361 min. remaining for the plan to fit before dawn.
2018-09-14 05:55:36.7:     but only 289 min till mid-Plan transit.
2018-09-14 05:55:36.7:     deferred (rising) for 289 min, until 14-Sep-2018 10:44:48 UTC
2018-09-14 05:55:36.8: Dispatcher cycle time: 1.3 sec.
2018-09-14 05:55:36.8: Acquire data for Observation V339_Del_TMO61...
2018-09-14 05:55:36.8:   (belongs to Project AAVSO_Monitoring, Plan V339_Del_TMO61)
2018-09-14 05:55:36.8: Doing 3 sets
2018-09-14 05:55:37.0: Send Observation V339_Del_TMO61 to ACP Sequencer
2018-09-14 06:01:04.3: Send Observation V339_Del_TMO61 to ACP Sequencer
2018-09-14 06:01:33.0: ACP ERROR: Run failed for unknown reason (see ACP run log)
2018-09-14 06:01:33.0: ACP data acquisition failed for Observation V339_Del_TMO61.
2018-09-14 06:01:33.0:   (Observation V339_Del_TMO61 interrupted by acquisition process failure.)
2018-09-14 06:01:37.5: -- Weather Unsafe --
2018-09-14 07:08:54.7: ++ Weather Safe ++
2018-09-14 07:08:55.7: ++ Observatory Startup ++
2018-09-14 07:08:55.8: Start ACP Sequencer's StartupObs script
2018-09-14 07:09:16.5: Open the observatory dome/roof
2018-09-14 07:09:41.1: **Dome closed while opening. Assuming weather closure.
2018-09-14 07:09:43.7: **EXCEPTION IN SCHEDULER:
2018-09-14 07:09:43.7: **Dome failed to open for reason other than hardware weather closure
2018-09-14 07:09:43.7: Traceback:
   at DC3.Scheduler.Engine.DoStartupIf(Boolean openDome)
   at DC3.Scheduler.Engine.Run()
2018-09-14 07:09:44.0: DOME/ROOF FAILURE: In addition, attempting to close your dome/roof this error occurred:
2018-09-14 07:09:44.0: DOME FAILURE: Dome reports shutter error status when asked to close.
2018-09-14 07:09:44.1: Run statistics:
2018-09-14 07:09:44.1:   Observations:   54
2018-09-14 07:09:44.1:   Considered:     30
2018-09-14 07:09:44.1:   Completed:      12
2018-09-14 07:09:44.1:   Skipped:        13
2018-09-14 07:09:44.1:   Never Eligible: 24
2018-09-14 07:09:44.1:   Failed:         5
2018-09-14 07:09:44.1: Shutter-Open efficiency: 52.91%
2018-09-14 07:09:44.1: Overall Efficiency: 99.90%
2018-09-14 07:09:44.1: Release ACP sequencer
Log closed at Fri, Sep 14 2018 07:09:45 UTC (actual time)