Log opened at Tue, Sep 25 2018 19:00:27 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2018-09-26 01:28:07.2: ++ Weather Safe ++
2018-09-26 01:42:47.0: ++ Observatory Startup ++
2018-09-26 01:42:47.0: Start ACP Sequencer's StartupObs script
2018-09-26 01:43:50.1: Open the observatory dome/roof
2018-09-26 01:45:04.2: Observatory startup succeeded, and dome opened successfully
2018-09-26 01:52:43.5: ++ Auto Focus ++
2018-09-26 01:52:43.6: Doing initial autofocus.
2018-09-26 01:52:43.6: Start special ACP AutoFocus script for scheduler
2018-09-26 01:56:45.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2018-09-26 01:56:47.7: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.7: Plan GSC_4030_204 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.7: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.7: Plan V0809_Cep has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan V1724_Aql has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan V1723_Aql has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan V1722_Aql has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan V1721_Aql has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan M56 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:47.8: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:48.2: Plan U_Aqr has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:48.2: Plan V339_Del_TMO61 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:48.2: Plan Mkn_501 has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:48.2: Plan AF_And has Monitor Mode. Time to resubmit it.
2018-09-26 01:56:48.8: Rising plan delay enabled for Plan GSC_3649-0825:
2018-09-26 01:56:48.8:     582 min. remaining to meet strict constraints.
2018-09-26 01:56:48.8:     608 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:48.8:     but only 257 min till mid-Plan transit.
2018-09-26 01:56:48.8:     deferred (rising) for 257 min, until 26-Sep-2018 06:14:00 UTC
2018-09-26 01:56:48.9: Rising plan delay enabled for Plan IRAS_19183+2339:
2018-09-26 01:56:48.9:     284 min. remaining to meet strict constraints.
2018-09-26 01:56:48.9:     590 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:48.9:     but only 2 min till mid-Plan transit.
2018-09-26 01:56:48.9:     deferred (rising) for 1 min, until 26-Sep-2018 01:57:52 UTC
2018-09-26 01:56:48.9: Rising plan delay enabled for Plan M56:
2018-09-26 01:56:48.9:     293 min. remaining to meet strict constraints.
2018-09-26 01:56:48.9:     608 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:48.9:     but only 7 min till mid-Plan transit.
2018-09-26 01:56:48.9:     deferred (rising) for 7 min, until 26-Sep-2018 02:04:15 UTC
2018-09-26 01:56:48.9: Rising plan delay enabled for Plan NGC_7790:
2018-09-26 01:56:48.9:     630 min. remaining to meet strict constraints.
2018-09-26 01:56:48.9:     608 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:48.9:     but only 288 min till mid-Plan transit.
2018-09-26 01:56:48.9:     deferred (rising) for 288 min, until 26-Sep-2018 06:45:17 UTC
2018-09-26 01:56:49.0: Rising plan delay enabled for Plan V0809_Cep:
2018-09-26 01:56:49.0:     579 min. remaining to meet strict constraints.
2018-09-26 01:56:49.0:     608 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:49.0:     but only 238 min till mid-Plan transit.
2018-09-26 01:56:49.0:     deferred (rising) for 238 min, until 26-Sep-2018 05:55:06 UTC
2018-09-26 01:56:49.0: Rising plan delay enabled for Plan V1722_Aql:
2018-09-26 01:56:49.0:     260 min. remaining to meet strict constraints.
2018-09-26 01:56:49.0:     608 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:49.0:     but only 5 min till mid-Plan transit.
2018-09-26 01:56:49.0:     deferred (rising) for 5 min, until 26-Sep-2018 02:01:49 UTC
2018-09-26 01:56:49.1: Rising plan delay enabled for Plan V339_Del_TMO61:
2018-09-26 01:56:49.1:     341 min. remaining to meet strict constraints.
2018-09-26 01:56:49.1:     608 min. remaining for the plan to fit before dawn.
2018-09-26 01:56:49.1:     but only 74 min till mid-Plan transit.
2018-09-26 01:56:49.1:     deferred (rising) for 74 min, until 26-Sep-2018 03:10:59 UTC
2018-09-26 01:56:49.1: Dispatcher cycle time: 1.4 sec.
2018-09-26 01:56:49.1: Acquire data for Observation Mkn_501...
2018-09-26 01:56:49.1:   (belongs to Project AAVSO_SXAC_Mkn_501, Plan Mkn_501)
2018-09-26 01:56:49.1: Doing 3 sets
2018-09-26 01:56:49.1: ++ Auto Focus ++
2018-09-26 01:56:49.2: Doing periodic autofocus.
2018-09-26 01:56:49.2: Start special ACP AutoFocus script for scheduler
2018-09-26 01:59:21.2: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2018-09-26 01:59:21.2: Send Observation Mkn_501 to ACP Sequencer
2018-09-26 02:07:54.2: Send Observation Mkn_501 to ACP Sequencer
2018-09-26 02:15:18.9: Send Observation Mkn_501 to ACP Sequencer
2018-09-26 02:22:48.7: Acquisition time: 1407.4500719 sec.
2018-09-26 02:22:48.7: Data for Observation Mkn_501 of Plan Mkn_501 acquired successfully.
2018-09-26 02:22:48.7: Plan Mkn_501 completed successfully.
2018-09-26 02:22:48.7: Image Efficiency: 63.9%
2018-09-26 02:22:48.7: Cycle Efficiency: 99.9%
2018-09-26 02:22:53.3: ++ Auto Focus ++
2018-09-26 02:22:53.3: Doing periodic autofocus.
2018-09-26 02:22:53.3: Start special ACP AutoFocus script for scheduler
2018-09-26 02:25:43.5: Next periodic autofocus ASAP after 26-Sep-2018 05:25:29 UTC
2018-09-26 02:25:47.1: Rising plan delay enabled for Plan AF_And:
2018-09-26 02:25:47.1:     610 min. remaining to meet strict constraints.
2018-09-26 02:25:47.1:     579 min. remaining for the plan to fit before dawn.
2018-09-26 02:25:47.1:     but only 304 min till mid-Plan transit.
2018-09-26 02:25:47.1:     deferred (rising) for 304 min, until 26-Sep-2018 07:30:19 UTC
2018-09-26 02:25:47.1: Rising plan delay enabled for Plan GSC_4030_204:
2018-09-26 02:25:47.1:     672 min. remaining to meet strict constraints.
2018-09-26 02:25:47.1:     579 min. remaining for the plan to fit before dawn.
2018-09-26 02:25:47.1:     but only 329 min till mid-Plan transit.
2018-09-26 02:25:47.1:     deferred (rising) for 329 min, until 26-Sep-2018 07:55:20 UTC
2018-09-26 02:25:47.1: Rising plan delay enabled for Plan U_Aqr:
2018-09-26 02:25:47.1:     290 min. remaining to meet strict constraints.
2018-09-26 02:25:47.1:     579 min. remaining for the plan to fit before dawn.
2018-09-26 02:25:47.1:     but only 144 min till mid-Plan transit.
2018-09-26 02:25:47.1:     deferred (rising) for 144 min, until 26-Sep-2018 04:50:32 UTC
2018-09-26 02:25:47.3: Dispatcher cycle time: 1.4 sec.
2018-09-26 02:25:47.3: Acquire data for Observation V1721_Aql...
2018-09-26 02:25:47.3:   (belongs to Project Nova_Monitoring, Plan V1721_Aql)
2018-09-26 02:25:54.9: Send Observation V1721_Aql to ACP Sequencer
2018-09-26 02:58:44.6: Acquisition time: 1969.7218596 sec.
2018-09-26 02:58:44.6: Data for Observation V1721_Aql of Plan V1721_Aql acquired successfully.
2018-09-26 02:58:44.6: Plan V1721_Aql completed successfully.
2018-09-26 02:58:44.6: Image Efficiency: 76.1%
2018-09-26 02:58:44.6: Cycle Efficiency: 99.9%
2018-09-26 02:58:49.8: Dispatcher cycle time: 0.2 sec.
2018-09-26 02:58:49.8: Acquire data for Observation V1722_Aql...
2018-09-26 02:58:49.8:   (belongs to Project Nova_Monitoring, Plan V1722_Aql)
2018-09-26 02:58:53.5: Send Observation V1722_Aql to ACP Sequencer
2018-09-26 03:06:36.9: ACP ERROR: Run failed for unknown reason (see ACP run log)
2018-09-26 03:06:36.9: ACP data acquisition failed for Observation V1722_Aql.
2018-09-26 03:06:36.9:   (Observation V1722_Aql interrupted by acquisition process failure.)
2018-09-26 03:06:40.8: -- Weather Unsafe --
2018-09-26 03:41:08.3: Dispatcher stopped at 26-Sep-2018 03:41:08 UTC
2018-09-26 03:41:08.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2018-09-26 04:11:42.7: ++ Weather Safe ++
2018-09-26 04:32:45.2: -- Weather Unsafe --
2018-09-26 05:26:22.7: ++ Weather Safe ++
2018-09-26 12:59:36.7: -- Weather Unsafe --
2018-09-26 20:39:16.6: Dispatcher started at 26-Sep-2018 20:39:16 UTC
2018-09-26 20:39:19.3: Run statistics:
2018-09-26 20:39:19.3:   Observations:   54
2018-09-26 20:39:19.3:   Considered:     28
2018-09-26 20:39:19.3:   Completed:      15
2018-09-26 20:39:19.3:   Skipped:        9
2018-09-26 20:39:19.3:   Never Eligible: 26
2018-09-26 20:39:19.3:   Failed:         4
Log closed at Wed, Sep 26 2018 20:39:19 UTC (actual time)