Log opened at Thu, Aug 02 2018 22:05:19 UTC (actual time) Current log level is Verbose ACP Expert Scheduler version 8.1.1 Full license: Arne Henden 2018-08-02 22:05:19.9: Internal clock initialized at 02-Aug-2018 22:05:19 UTC 2018-08-02 22:05:20.0: Loaded Constraint plugin AirMass 2018-08-02 22:05:20.0: Loaded Constraint plugin AirmassRange 2018-08-02 22:05:20.0: Loaded Constraint plugin Horizon 2018-08-02 22:05:20.0: Loaded Constraint plugin HourAngle 2018-08-02 22:05:20.0: Loaded Constraint plugin MoonAvoid 2018-08-02 22:05:20.0: Loaded Constraint plugin MoonDown 2018-08-02 22:05:20.0: Loaded Constraint plugin SkyCondition 2018-08-02 22:05:20.0: Loaded Constraint plugin TimeRange 2018-08-02 22:05:20.8: 60 plans are now pending. 2018-08-02 22:05:20.8: Attach ACP sequencer 2018-08-02 22:05:28.4: There is no controllable dome or roof. 2018-08-02 22:05:29.0: Sequencer does sky flats 2018-08-02 22:05:44.6: Weather successfully connected. Wait for valid data... 2018-08-03 02:48:37.3: ++ Weather Safe ++ 2018-08-03 02:56:19.6: Dispatcher started at 03-Aug-2018 02:56:19 UTC 2018-08-03 02:56:24.8: ++ Observatory Startup ++ 2018-08-03 02:56:24.8: Start ACP Sequencer's StartupObs script 2018-08-03 02:56:54.1: Open the observatory dome/roof 2018-08-03 02:58:04.0: Observatory startup succeeded, and dome opened successfully 2018-08-03 03:02:08.7: ++ Auto Focus ++ 2018-08-03 03:02:08.7: Doing initial autofocus. 2018-08-03 03:02:08.7: Start special ACP AutoFocus script for scheduler 2018-08-03 03:07:32.4: Next periodic autofocus ASAP after 01-Jan-0001 03:00:00 UTC 2018-08-03 03:07:35.7: Plan Mkn_501 has Monitor Mode. Time to resubmit it. 2018-08-03 03:07:35.7: Rising plan delay enabled for Plan 2MASS_J19395313+0557550: 2018-08-03 03:07:35.7: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:35.7: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.7: deferred (rising) for 141 min, until 03-Aug-2018 05:29:01 UTC 2018-08-03 03:07:35.8: Rising plan delay enabled for Plan CY_UMa: 2018-08-03 03:07:35.8: 13 min. remaining to meet strict constraints. 2018-08-03 03:07:35.8: -336 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.8: ending rising plan delay. Plan is now eligible. 2018-08-03 03:07:35.9: Rising plan delay enabled for Plan IRAS_19067+2158: 2018-08-03 03:07:35.9: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:35.9: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.9: but only 132 min till mid-Plan transit. 2018-08-03 03:07:35.9: deferred (rising) for 131 min, until 03-Aug-2018 05:18:39 UTC 2018-08-03 03:07:35.9: Rising plan delay enabled for Plan IRAS_19183+2339: 2018-08-03 03:07:35.9: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:35.9: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.9: deferred (rising) for 141 min, until 03-Aug-2018 05:29:01 UTC 2018-08-03 03:07:35.9: Rising plan delay enabled for Plan M56: 2018-08-03 03:07:35.9: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:35.9: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.9: but only 140 min till mid-Plan transit. 2018-08-03 03:07:35.9: deferred (rising) for 138 min, until 03-Aug-2018 05:26:01 UTC 2018-08-03 03:07:35.9: Rising plan delay enabled for Plan Mkn_501: 2018-08-03 03:07:35.9: 309 min. remaining to meet strict constraints. 2018-08-03 03:07:35.9: 496 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.9: but only 6 min till mid-Plan transit. 2018-08-03 03:07:35.9: deferred (rising) for 6 min, until 03-Aug-2018 03:14:13 UTC 2018-08-03 03:07:35.9: Rising plan delay enabled for Plan NSVS_11201899: 2018-08-03 03:07:35.9: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:35.9: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:35.9: but only 133 min till mid-Plan transit. 2018-08-03 03:07:35.9: deferred (rising) for 131 min, until 03-Aug-2018 05:19:33 UTC 2018-08-03 03:07:36.0: Rising plan delay enabled for Plan V1015_Her: 2018-08-03 03:07:36.0: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.0: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.0: but only 56 min till mid-Plan transit. 2018-08-03 03:07:36.0: deferred (rising) for 54 min, until 03-Aug-2018 04:02:21 UTC 2018-08-03 03:07:36.0: Rising plan delay enabled for Plan V1721_Aql: 2018-08-03 03:07:36.0: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.0: 463 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.0: but only 121 min till mid-Plan transit. 2018-08-03 03:07:36.0: deferred (rising) for 118 min, until 03-Aug-2018 05:06:07 UTC 2018-08-03 03:07:36.0: Rising plan delay enabled for Plan V1722_Aql: 2018-08-03 03:07:36.0: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.0: 463 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.0: but only 129 min till mid-Plan transit. 2018-08-03 03:07:36.0: deferred (rising) for 126 min, until 03-Aug-2018 05:13:47 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V1723_Aql: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 476 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: but only 109 min till mid-Plan transit. 2018-08-03 03:07:36.1: deferred (rising) for 107 min, until 03-Aug-2018 04:55:20 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V1724_Aql: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 476 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: but only 114 min till mid-Plan transit. 2018-08-03 03:07:36.1: deferred (rising) for 112 min, until 03-Aug-2018 05:00:16 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V1904_Cyg: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: deferred (rising) for 141 min, until 03-Aug-2018 05:29:01 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V1907_Cyg: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: deferred (rising) for 141 min, until 03-Aug-2018 05:29:01 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V2481_Cyg: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: deferred (rising) for 141 min, until 03-Aug-2018 05:29:01 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V339_Del_TMO61: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 479 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: deferred (rising) for 141 min, until 03-Aug-2018 05:28:57 UTC 2018-08-03 03:07:36.1: Rising plan delay enabled for Plan V841_Oph: 2018-08-03 03:07:36.1: 143 min. remaining to meet strict constraints. 2018-08-03 03:07:36.1: 476 min. remaining for the plan to fit before dawn. 2018-08-03 03:07:36.1: but only 1 min till mid-Plan transit. 2018-08-03 03:07:36.1: ending rising plan delay. Plan is now eligible. 2018-08-03 03:07:36.2: Dispatcher cycle time: 1.9 sec. 2018-08-03 03:07:36.2: Acquire data for Observation SDSSJ121027+502735... 2018-08-03 03:07:36.2: (belongs to Project NMSU, Plan SDSSJ121027+502735) 2018-08-03 03:07:36.2: ++ Auto Focus ++ 2018-08-03 03:07:36.2: Doing periodic autofocus. 2018-08-03 03:07:36.2: Start special ACP AutoFocus script for scheduler 2018-08-03 03:11:27.6: ** Autofocus failed (n=1), will retry ASAP after 0 min. 2018-08-03 03:11:27.6: Send Observation SDSSJ121027+502735 to ACP Sequencer 2018-08-03 03:26:04.4: Acquisition time: 876.79954 sec. 2018-08-03 03:26:04.4: Data for Observation SDSSJ121027+502735 of Plan SDSSJ121027+502735 acquired successfully. 2018-08-03 03:26:04.4: Plan SDSSJ121027+502735 completed successfully. 2018-08-03 03:26:04.4: Image Efficiency: 68.3% 2018-08-03 03:26:04.4: Cycle Efficiency: 99.8% 2018-08-03 03:26:16.8: ++ Auto Focus ++ 2018-08-03 03:26:16.8: Doing periodic autofocus. 2018-08-03 03:26:16.8: Start special ACP AutoFocus script for scheduler 2018-08-03 03:31:39.6: ** Autofocus failed (n=2), will retry ASAP after 5 min. 2018-08-03 03:31:43.0: Dispatcher cycle time: 1.2 sec. 2018-08-03 03:31:43.0: Acquire data for Observation Mkn_501... 2018-08-03 03:31:43.0: (belongs to Project AAVSO_SXAC_Mkn_501, Plan Mkn_501) 2018-08-03 03:31:43.0: Doing 3 sets 2018-08-03 03:31:49.3: Send Observation Mkn_501 to ACP Sequencer 2018-08-03 03:40:33.8: ACP ERROR: Run failed for unknown reason (see ACP run log) 2018-08-03 03:40:33.8: ACP data acquisition failed for Observation Mkn_501. 2018-08-03 03:40:33.8: (Observation Mkn_501 interrupted by acquisition process failure.) 2018-08-03 03:40:35.9: -- Weather Unsafe -- 2018-08-03 03:41:05.7: Dispatcher stopped at 03-Aug-2018 03:41:05 UTC 2018-08-03 03:41:05.7: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED** 2018-08-03 04:14:21.4: ++ Weather Safe ++ 2018-08-03 04:32:28.0: -- Weather Unsafe -- 2018-08-03 08:37:33.0: ++ Weather Safe ++ 2018-08-03 12:02:43.3: -- Weather Unsafe -- 2018-08-04 03:44:33.0: ++ Weather Safe ++ 2018-08-04 03:50:58.4: -- Weather Unsafe -- 2018-08-04 04:33:18.5: ++ Weather Safe ++ 2018-08-04 09:48:54.7: -- Weather Unsafe -- 2018-08-04 10:43:20.8: ++ Weather Safe ++ 2018-08-04 11:59:15.7: -- Weather Unsafe -- 2018-08-05 02:54:18.0: ++ Weather Safe ++ 2018-08-05 04:12:33.7: -- Weather Unsafe -- 2018-08-05 09:05:13.9: ++ Weather Safe ++ 2018-08-05 10:47:08.6: -- Weather Unsafe -- 2018-08-05 11:17:59.9: ++ Weather Safe ++ 2018-08-05 11:59:22.1: -- Weather Unsafe -- 2018-08-07 03:01:09.9: ++ Weather Safe ++ 2018-08-07 03:22:51.4: -- Weather Unsafe -- 2018-08-07 04:02:50.1: ++ Weather Safe ++ 2018-08-07 05:08:52.7: -- Weather Unsafe -- 2018-08-07 05:44:37.0: ++ Weather Safe ++ 2018-08-07 06:24:21.8: -- Weather Unsafe -- 2018-08-07 08:17:46.2: ++ Weather Safe ++ 2018-08-07 12:02:59.3: -- Weather Unsafe -- 2018-08-08 02:47:35.4: ++ Weather Safe ++ 2018-08-08 04:33:39.4: -- Weather Unsafe -- 2018-08-08 06:32:20.7: ++ Weather Safe ++ 2018-08-08 10:21:34.9: -- Weather Unsafe -- 2018-08-09 04:16:42.8: ++ Weather Safe ++ 2018-08-09 06:53:25.6: -- Weather Unsafe -- 2018-08-09 08:10:01.8: ++ Weather Safe ++ 2018-08-09 08:14:31.0: -- Weather Unsafe -- 2018-08-09 08:47:53.6: ++ Weather Safe ++ 2018-08-09 12:12:49.5: -- Weather Unsafe -- 2018-08-10 03:29:37.1: ++ Weather Safe ++ 2018-08-10 03:31:48.2: -- Weather Unsafe -- 2018-08-10 04:08:05.1: ++ Weather Safe ++ 2018-08-10 06:53:08.1: -- Weather Unsafe -- 2018-08-10 08:10:59.0: ++ Weather Safe ++ 2018-08-10 08:17:32.1: -- Weather Unsafe -- 2018-08-10 09:31:45.3: ++ Weather Safe ++ 2018-08-10 09:33:04.6: -- Weather Unsafe -- 2018-08-10 10:03:04.1: ++ Weather Safe ++ 2018-08-10 10:49:44.0: -- Weather Unsafe -- 2018-08-10 11:27:03.8: ++ Weather Safe ++ 2018-08-10 11:35:10.2: -- Weather Unsafe -- 2018-08-11 02:45:58.8: ++ Weather Safe ++ 2018-08-11 03:36:45.6: -- Weather Unsafe -- 2018-08-11 07:48:41.8: ++ Weather Safe ++ 2018-08-11 11:13:14.5: -- Weather Unsafe -- 2018-08-12 05:05:14.0: ++ Weather Safe ++ 2018-08-12 05:16:15.4: -- Weather Unsafe -- 2018-08-12 06:11:29.9: ++ Weather Safe ++ 2018-08-12 12:04:56.4: -- Weather Unsafe -- 2018-08-13 02:36:42.9: Dispatcher started at 13-Aug-2018 02:36:42 UTC 2018-08-13 02:36:44.5: Run statistics: 2018-08-13 02:36:44.5: Observations: 53 2018-08-13 02:36:44.5: Considered: 10 2018-08-13 02:36:44.5: Completed: 1 2018-08-13 02:36:44.5: Skipped: 5 2018-08-13 02:36:44.5: Never Eligible: 43 2018-08-13 02:36:44.5: Failed: 4 Log closed at Mon, Aug 13 2018 02:36:44 UTC (actual time)