Log opened at Wed, Aug 29 2018 03:03:29 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2018-08-29 03:03:29.4: Internal clock initialized at 29-Aug-2018 03:03:29 UTC
2018-08-29 03:03:29.5: Loaded Constraint plugin AirMass
2018-08-29 03:03:29.5: Loaded Constraint plugin AirmassRange
2018-08-29 03:03:29.5: Loaded Constraint plugin Horizon
2018-08-29 03:03:29.5: Loaded Constraint plugin HourAngle
2018-08-29 03:03:29.5: Loaded Constraint plugin MoonAvoid
2018-08-29 03:03:29.5: Loaded Constraint plugin MoonDown
2018-08-29 03:03:29.5: Loaded Constraint plugin SkyCondition
2018-08-29 03:03:29.5: Loaded Constraint plugin TimeRange
2018-08-29 03:03:30.3: 48 plans are now pending.
2018-08-29 03:03:30.3: Attach ACP sequencer
2018-08-29 03:03:36.5: There is no controllable dome or roof.
2018-08-29 03:03:38.4: Sequencer does sky flats
2018-08-29 03:03:53.0: Weather successfully connected. Wait for valid data...
2018-08-29 03:07:07.3: Dispatcher started at 29-Aug-2018 03:07:07 UTC
2018-08-29 04:35:40.1: ++ Weather Safe ++
2018-08-29 04:35:41.1: ++ Observatory Startup ++
2018-08-29 04:35:41.1: Start ACP Sequencer's StartupObs script
2018-08-29 04:36:09.4: Open the observatory dome/roof
2018-08-29 04:37:23.8: Observatory startup succeeded, and dome opened successfully
2018-08-29 04:37:23.8: ++ Auto Focus ++
2018-08-29 04:37:23.8: Doing initial autofocus.
2018-08-29 04:37:23.8: Start special ACP AutoFocus script for scheduler
2018-08-29 04:43:13.9: Next periodic autofocus ASAP after 29-Aug-2018 07:42:58 UTC
2018-08-29 04:43:18.6: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it.
2018-08-29 04:43:18.6: Plan Mkn_501 has Monitor Mode. Time to resubmit it.
2018-08-29 04:43:18.6: Rising plan delay enabled for Plan AF_And:
2018-08-29 04:43:18.6:     582 min. remaining to meet strict constraints.
2018-08-29 04:43:18.6:     389 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.6:     but only 260 min till mid-Plan transit.
2018-08-29 04:43:18.6:     deferred (rising) for 257 min, until 29-Aug-2018 09:00:34 UTC
2018-08-29 04:43:18.7: Rising plan delay enabled for Plan BC_Lac:
2018-08-29 04:43:18.7:     463 min. remaining to meet strict constraints.
2018-08-29 04:43:18.7:     405 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.7:     but only 142 min till mid-Plan transit.
2018-08-29 04:43:18.7:     deferred (rising) for 140 min, until 29-Aug-2018 07:03:57 UTC
2018-08-29 04:43:18.7: Rising plan delay enabled for Plan CM_Cas:
2018-08-29 04:43:18.7:     702 min. remaining to meet strict constraints.
2018-08-29 04:43:18.7:     405 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.7:     but only 337 min till mid-Plan transit.
2018-08-29 04:43:18.7:     deferred (rising) for 336 min, until 29-Aug-2018 10:19:21 UTC
2018-08-29 04:43:18.7: Rising plan delay enabled for Plan GSC_3649-0825:
2018-08-29 04:43:18.7:     526 min. remaining to meet strict constraints.
2018-08-29 04:43:18.7:     417 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.7:     but only 198 min till mid-Plan transit.
2018-08-29 04:43:18.7:     deferred (rising) for 198 min, until 29-Aug-2018 08:01:24 UTC
2018-08-29 04:43:18.8: Rising plan delay enabled for Plan GSC_4030_204:
2018-08-29 04:43:18.8:     644 min. remaining to meet strict constraints.
2018-08-29 04:43:18.8:     420 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.8:     but only 301 min till mid-Plan transit.
2018-08-29 04:43:18.8:     deferred (rising) for 301 min, until 29-Aug-2018 09:44:28 UTC
2018-08-29 04:43:18.8: Rising plan delay enabled for Plan NGC_7790:
2018-08-29 04:43:18.8:     573 min. remaining to meet strict constraints.
2018-08-29 04:43:18.8:     403 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.8:     but only 222 min till mid-Plan transit.
2018-08-29 04:43:18.8:     deferred (rising) for 220 min, until 29-Aug-2018 08:23:59 UTC
2018-08-29 04:43:18.9: Rising plan delay enabled for Plan U_Aqr:
2018-08-29 04:43:18.9:     262 min. remaining to meet strict constraints.
2018-08-29 04:43:18.9:     391 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.9:     but only 101 min till mid-Plan transit.
2018-08-29 04:43:18.9:     deferred (rising) for 98 min, until 29-Aug-2018 06:22:04 UTC
2018-08-29 04:43:18.9: Rising plan delay enabled for Plan V0524_Cas:
2018-08-29 04:43:18.9:     639 min. remaining to meet strict constraints.
2018-08-29 04:43:18.9:     405 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.9:     but only 271 min till mid-Plan transit.
2018-08-29 04:43:18.9:     deferred (rising) for 269 min, until 29-Aug-2018 09:12:36 UTC
2018-08-29 04:43:18.9: Rising plan delay enabled for Plan V0809_Cep:
2018-08-29 04:43:18.9:     522 min. remaining to meet strict constraints.
2018-08-29 04:43:18.9:     389 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:18.9:     but only 165 min till mid-Plan transit.
2018-08-29 04:43:18.9:     deferred (rising) for 162 min, until 29-Aug-2018 07:25:51 UTC
2018-08-29 04:43:19.0: Rising plan delay enabled for Plan V1904_Cyg:
2018-08-29 04:43:19.0:     371 min. remaining to meet strict constraints.
2018-08-29 04:43:19.0:     405 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:19.0:     but only 70 min till mid-Plan transit.
2018-08-29 04:43:19.0:     deferred (rising) for 68 min, until 29-Aug-2018 05:51:53 UTC
2018-08-29 04:43:19.0: Rising plan delay enabled for Plan V1907_Cyg:
2018-08-29 04:43:19.0:     388 min. remaining to meet strict constraints.
2018-08-29 04:43:19.0:     405 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:19.0:     but only 76 min till mid-Plan transit.
2018-08-29 04:43:19.0:     deferred (rising) for 75 min, until 29-Aug-2018 05:58:23 UTC
2018-08-29 04:43:19.0: Rising plan delay enabled for Plan V2481_Cyg:
2018-08-29 04:43:19.0:     358 min. remaining to meet strict constraints.
2018-08-29 04:43:19.0:     405 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:19.0:     but only 49 min till mid-Plan transit.
2018-08-29 04:43:19.0:     deferred (rising) for 48 min, until 29-Aug-2018 05:31:30 UTC
2018-08-29 04:43:19.0: Rising plan delay enabled for Plan V339_Del_TMO61:
2018-08-29 04:43:19.0:     284 min. remaining to meet strict constraints.
2018-08-29 04:43:19.0:     404 min. remaining for the plan to fit before dawn.
2018-08-29 04:43:19.0:     but only 8 min till mid-Plan transit.
2018-08-29 04:43:19.0:     deferred (rising) for 7 min, until 29-Aug-2018 04:50:26 UTC
2018-08-29 04:43:19.2: Dispatcher cycle time: 2.4 sec.
2018-08-29 04:43:19.2: Acquire data for Observation Mkn_501...
2018-08-29 04:43:19.2:   (belongs to Project AAVSO_SXAC_Mkn_501, Plan Mkn_501)
2018-08-29 04:43:19.2: Doing 3 sets
2018-08-29 04:43:25.0: Send Observation Mkn_501 to ACP Sequencer
2018-08-29 04:52:49.3: Send Observation Mkn_501 to ACP Sequencer
2018-08-29 05:00:30.6: Send Observation Mkn_501 to ACP Sequencer
2018-08-29 05:08:23.6: Acquisition time: 1498.6166322 sec.
2018-08-29 05:08:23.6: Data for Observation Mkn_501 of Plan Mkn_501 acquired successfully.
2018-08-29 05:08:23.7: Plan Mkn_501 completed successfully.
2018-08-29 05:08:23.7: Image Efficiency: 60.0%
2018-08-29 05:08:23.7: Cycle Efficiency: 99.8%
2018-08-29 05:08:28.1: Dispatcher cycle time: 0.2 sec.
2018-08-29 05:08:28.1: Acquire data for Observation V1722_Aql...
2018-08-29 05:08:28.1:   (belongs to Project Nova_Monitoring, Plan V1722_Aql)
2018-08-29 05:08:35.7: Send Observation V1722_Aql to ACP Sequencer
2018-08-29 05:42:43.6: Acquisition time: 2047.909197 sec.
2018-08-29 05:42:43.6: Data for Observation V1722_Aql of Plan V1722_Aql acquired successfully.
2018-08-29 05:42:43.6: Plan V1722_Aql completed successfully.
2018-08-29 05:42:43.6: Image Efficiency: 73.2%
2018-08-29 05:42:43.6: Cycle Efficiency: 100.0%
2018-08-29 05:42:45.7: Dispatcher cycle time: 1.0 sec.
2018-08-29 05:42:45.7: Acquire data for Observation V339_Del_TMO61...
2018-08-29 05:42:45.7:   (belongs to Project AAVSO_Monitoring, Plan V339_Del_TMO61)
2018-08-29 05:42:45.7: Doing 3 sets
2018-08-29 05:42:48.3: Send Observation V339_Del_TMO61 to ACP Sequencer
2018-08-29 05:46:17.4: ** ACP script "AcquireScheduler" failed:
2018-08-29 05:46:17.4: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 1585 column 17.

2018-08-29 05:46:20.5: ACP ERROR: Run failed due to script error (see ACP run log)
2018-08-29 05:46:20.5: ACP data acquisition failed for Observation V339_Del_TMO61.
2018-08-29 05:46:20.5:   (Observation V339_Del_TMO61 interrupted by acquisition process failure.)
2018-08-29 05:46:24.7: -- Weather Unsafe --
2018-08-29 06:16:49.9: ++ Weather Safe ++
2018-08-29 06:16:50.9: ++ Observatory Startup ++
2018-08-29 06:16:50.9: Start ACP Sequencer's StartupObs script
2018-08-29 06:17:11.6: Open the observatory dome/roof
2018-08-29 06:18:26.9: Observatory startup succeeded, and dome opened successfully
2018-08-29 06:18:28.0: ++ Auto Focus ++
2018-08-29 06:18:28.0: Doing initial autofocus.
2018-08-29 06:18:28.0: Start special ACP AutoFocus script for scheduler
2018-08-29 06:22:11.8: Next periodic autofocus ASAP after 29-Aug-2018 09:21:58 UTC
2018-08-29 06:22:12.1: Rising plan delay enabled for Plan FU_Cam:
2018-08-29 06:22:12.1:     687 min. remaining to meet strict constraints.
2018-08-29 06:22:12.1:     306 min. remaining for the plan to fit before dawn.
2018-08-29 06:22:12.1:     deferred (rising) for 304 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 06:22:12.1: Rising plan delay enabled for Plan FW_Cam:
2018-08-29 06:22:12.1:     700 min. remaining to meet strict constraints.
2018-08-29 06:22:12.1:     306 min. remaining for the plan to fit before dawn.
2018-08-29 06:22:12.1:     deferred (rising) for 304 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 06:22:12.1: Rising plan delay enabled for Plan U_Aqr:
2018-08-29 06:22:12.1:     163 min. remaining to meet strict constraints.
2018-08-29 06:22:12.1:     292 min. remaining for the plan to fit before dawn.
2018-08-29 06:22:12.1:     but only 2 min till mid-Plan transit.
2018-08-29 06:22:12.1:     ending rising plan delay. Plan is now eligible.
2018-08-29 06:22:12.3: Dispatcher cycle time: 0.2 sec.
2018-08-29 06:22:12.3: Acquire data for Observation V1721_Aql...
2018-08-29 06:22:12.3:   (belongs to Project Nova_Monitoring, Plan V1721_Aql)
2018-08-29 06:22:16.6: Send Observation V1721_Aql to ACP Sequencer
2018-08-29 06:56:02.4: Acquisition time: 2025.7883582 sec.
2018-08-29 06:56:02.4: Data for Observation V1721_Aql of Plan V1721_Aql acquired successfully.
2018-08-29 06:56:02.4: Plan V1721_Aql completed successfully.
2018-08-29 06:56:02.4: Image Efficiency: 74.0%
2018-08-29 06:56:02.4: Cycle Efficiency: 100.0%
2018-08-29 06:56:06.7: Rising plan delay enabled for Plan FY_Cam:
2018-08-29 06:56:06.7:     659 min. remaining to meet strict constraints.
2018-08-29 06:56:06.7:     272 min. remaining for the plan to fit before dawn.
2018-08-29 06:56:06.7:     deferred (rising) for 270 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 06:56:06.7: Rising plan delay enabled for Plan V0965_Per:
2018-08-29 06:56:06.7:     590 min. remaining to meet strict constraints.
2018-08-29 06:56:06.7:     268 min. remaining for the plan to fit before dawn.
2018-08-29 06:56:06.7:     deferred (rising) for 266 min, until 29-Aug-2018 11:22:47 UTC
2018-08-29 06:56:06.9: Dispatcher cycle time: 0.2 sec.
2018-08-29 06:56:06.9: Acquire data for Observation U_Aqr...
2018-08-29 06:56:06.9:   (belongs to Project AAVSO_Monitoring, Plan U_Aqr)
2018-08-29 06:56:08.6: Send Observation U_Aqr to ACP Sequencer
2018-08-29 07:27:53.5: Acquisition time: 1904.9037458 sec.
2018-08-29 07:27:53.5: Data for Observation U_Aqr of Plan U_Aqr acquired successfully.
2018-08-29 07:27:53.5: Plan U_Aqr completed successfully.
2018-08-29 07:27:53.5: Image Efficiency: 73.5%
2018-08-29 07:27:53.5: Cycle Efficiency: 100.0%
2018-08-29 07:27:57.3: Rising plan delay enabled for Plan HI_Cam:
2018-08-29 07:27:57.3:     689 min. remaining to meet strict constraints.
2018-08-29 07:27:57.3:     240 min. remaining for the plan to fit before dawn.
2018-08-29 07:27:57.3:     deferred (rising) for 238 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 07:27:57.4: Rising plan delay enabled for Plan V0603_Per:
2018-08-29 07:27:57.4:     619 min. remaining to meet strict constraints.
2018-08-29 07:27:57.4:     240 min. remaining for the plan to fit before dawn.
2018-08-29 07:27:57.4:     deferred (rising) for 238 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 07:27:57.4: Rising plan delay enabled for Plan V0809_Cep:
2018-08-29 07:27:57.4:     358 min. remaining to meet strict constraints.
2018-08-29 07:27:57.4:     225 min. remaining for the plan to fit before dawn.
2018-08-29 07:27:57.4:     but only 1 min till mid-Plan transit.
2018-08-29 07:27:57.4:     ending rising plan delay. Plan is now eligible.
2018-08-29 07:27:57.4: Rising plan delay enabled for Plan WZ_Per:
2018-08-29 07:27:57.4:     598 min. remaining to meet strict constraints.
2018-08-29 07:27:57.4:     240 min. remaining for the plan to fit before dawn.
2018-08-29 07:27:57.4:     deferred (rising) for 238 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 07:27:57.5: Dispatcher cycle time: 0.2 sec.
2018-08-29 07:27:57.5: Acquire data for Observation BC_Lac...
2018-08-29 07:27:57.5:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan BC_Lac)
2018-08-29 07:28:05.2: Send Observation BC_Lac to ACP Sequencer
2018-08-29 07:44:36.5: Acquisition time: 991.3661412 sec.
2018-08-29 07:44:36.5: Data for Observation BC_Lac of Plan BC_Lac acquired successfully.
2018-08-29 07:44:36.5: Plan BC_Lac completed successfully.
2018-08-29 07:44:36.5: Image Efficiency: 39.3%
2018-08-29 07:44:36.5: Cycle Efficiency: 100.0%
2018-08-29 07:44:44.8: Dispatcher cycle time: 0.5 sec.
2018-08-29 07:44:44.8: Acquire data for Observation V0809_Cep...
2018-08-29 07:44:44.8:   (belongs to Project Nova_Monitoring, Plan V0809_Cep)
2018-08-29 07:44:52.7: Send Observation V0809_Cep to ACP Sequencer
2018-08-29 08:16:21.8: Acquisition time: 1889.069718 sec.
2018-08-29 08:16:21.8: Data for Observation V0809_Cep of Plan V0809_Cep acquired successfully.
2018-08-29 08:16:21.8: Plan V0809_Cep completed successfully.
2018-08-29 08:16:21.8: Image Efficiency: 74.1%
2018-08-29 08:16:21.8: Cycle Efficiency: 100.0%
2018-08-29 08:16:24.9: Rising plan delay enabled for Plan HO_Cam:
2018-08-29 08:16:24.9:     670 min. remaining to meet strict constraints.
2018-08-29 08:16:24.9:     191 min. remaining for the plan to fit before dawn.
2018-08-29 08:16:24.9:     deferred (rising) for 190 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 08:16:25.0: Dispatcher cycle time: 0.2 sec.
2018-08-29 08:16:25.0: Acquire data for Observation GSC_3649-0825...
2018-08-29 08:16:25.0:   (belongs to Project Staels, Plan GSC_3649-0825)
2018-08-29 08:16:25.0: Send Observation GSC_3649-0825 to ACP Sequencer
2018-08-29 08:20:53.1: Acquisition time: 268.0708708 sec.
2018-08-29 08:20:53.1: Data for Observation GSC_3649-0825 of Plan GSC_3649-0825 acquired successfully.
2018-08-29 08:20:53.1: Plan GSC_3649-0825 completed successfully.
2018-08-29 08:20:53.1: Image Efficiency: 50.3%
2018-08-29 08:20:53.1: Cycle Efficiency: 99.9%
2018-08-29 08:20:55.3: Rising plan delay enabled for Plan HX_Aur:
2018-08-29 08:20:55.3:     636 min. remaining to meet strict constraints.
2018-08-29 08:20:55.3:     187 min. remaining for the plan to fit before dawn.
2018-08-29 08:20:55.3:     deferred (rising) for 185 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 08:20:55.4: Dispatcher cycle time: 0.1 sec.
2018-08-29 08:20:55.4: Acquire data for Observation V1907_Cyg...
2018-08-29 08:20:55.4:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V1907_Cyg)
2018-08-29 08:20:58.5: Send Observation V1907_Cyg to ACP Sequencer
2018-08-29 08:37:43.7: Acquisition time: 1005.1409654 sec.
2018-08-29 08:37:43.7: Data for Observation V1907_Cyg of Plan V1907_Cyg acquired successfully.
2018-08-29 08:37:43.7: Plan V1907_Cyg completed successfully.
2018-08-29 08:37:43.7: Image Efficiency: 38.8%
2018-08-29 08:37:43.7: Cycle Efficiency: 100.0%
2018-08-29 08:37:50.0: Dispatcher cycle time: 0.1 sec.
2018-08-29 08:37:50.0: Acquire data for Observation NGC_7790...
2018-08-29 08:37:50.0:   (belongs to Project AAVSO_Monitoring, Plan NGC_7790)
2018-08-29 08:37:57.7: Send Observation NGC_7790 to ACP Sequencer
2018-08-29 08:56:15.0: Acquisition time: 1097.3371274 sec.
2018-08-29 08:56:15.0: Data for Observation NGC_7790 of Plan NGC_7790 acquired successfully.
2018-08-29 08:56:15.0: Plan NGC_7790 completed successfully.
2018-08-29 08:56:15.0: Image Efficiency: 42.4%
2018-08-29 08:56:15.0: Cycle Efficiency: 100.0%
2018-08-29 08:56:22.7: Rising plan delay enabled for Plan HQ_Aur:
2018-08-29 08:56:22.7:     633 min. remaining to meet strict constraints.
2018-08-29 08:56:22.7:     151 min. remaining for the plan to fit before dawn.
2018-08-29 08:56:22.7:     deferred (rising) for 150 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 08:56:22.8: Dispatcher cycle time: 0.1 sec.
2018-08-29 08:56:22.8: Acquire data for Observation V1904_Cyg...
2018-08-29 08:56:22.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V1904_Cyg)
2018-08-29 08:56:22.9: Send Observation V1904_Cyg to ACP Sequencer
2018-08-29 09:13:57.6: Acquisition time: 1054.7646526 sec.
2018-08-29 09:13:57.6: Data for Observation V1904_Cyg of Plan V1904_Cyg acquired successfully.
2018-08-29 09:13:57.6: Plan V1904_Cyg completed successfully.
2018-08-29 09:13:57.6: Image Efficiency: 37.0%
2018-08-29 09:13:57.6: Cycle Efficiency: 100.0%
2018-08-29 09:13:59.9: Rising plan delay enabled for Plan V0524_Cas:
2018-08-29 09:13:59.9:     368 min. remaining to meet strict constraints.
2018-08-29 09:13:59.9:     134 min. remaining for the plan to fit before dawn.
2018-08-29 09:13:59.9:     but only 0 min till mid-Plan transit.
2018-08-29 09:13:59.9:     ending rising plan delay. Plan is now eligible.
2018-08-29 09:14:00.0: Dispatcher cycle time: 0.1 sec.
2018-08-29 09:14:00.0: Acquire data for Observation AF_And...
2018-08-29 09:14:00.0:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2018-08-29 09:14:05.1: Send Observation AF_And to ACP Sequencer
2018-08-29 09:46:48.5: Acquisition time: 1963.4662486 sec.
2018-08-29 09:46:48.5: Data for Observation AF_And of Plan AF_And acquired successfully.
2018-08-29 09:46:48.6: Plan AF_And completed successfully.
2018-08-29 09:46:48.6: Image Efficiency: 66.2%
2018-08-29 09:46:48.6: Cycle Efficiency: 100.0%
2018-08-29 09:46:55.4: ++ Auto Focus ++
2018-08-29 09:46:55.4: Doing periodic autofocus.
2018-08-29 09:46:55.4: Start special ACP AutoFocus script for scheduler
2018-08-29 09:50:07.5: Next periodic autofocus ASAP after 29-Aug-2018 12:49:48 UTC
2018-08-29 09:50:09.5: Dispatcher cycle time: 1.2 sec.
2018-08-29 09:50:09.5: Acquire data for Observation GSC_4030_204...
2018-08-29 09:50:09.5:   (belongs to Project Staels, Plan GSC_4030_204)
2018-08-29 09:50:17.0: Send Observation GSC_4030_204 to ACP Sequencer
2018-08-29 09:52:40.1: Acquisition time: 143.0678513 sec.
2018-08-29 09:52:40.1: Data for Observation GSC_4030_204 of Plan GSC_4030_204 acquired successfully.
2018-08-29 09:52:40.1: Plan GSC_4030_204 completed successfully.
2018-08-29 09:52:40.1: Image Efficiency: 20.8%
2018-08-29 09:52:40.1: Cycle Efficiency: 99.2%
2018-08-29 09:52:48.4: Dispatcher cycle time: 0.4 sec.
2018-08-29 09:52:48.4: Acquire data for Observation V0524_Cas...
2018-08-29 09:52:48.4:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0524_Cas)
2018-08-29 09:52:51.9: Send Observation V0524_Cas to ACP Sequencer
2018-08-29 10:08:28.0: Acquisition time: 936.048444 sec.
2018-08-29 10:08:28.0: Data for Observation V0524_Cas of Plan V0524_Cas acquired successfully.
2018-08-29 10:08:28.0: Plan V0524_Cas completed successfully.
2018-08-29 10:08:28.0: Image Efficiency: 41.6%
2018-08-29 10:08:28.0: Cycle Efficiency: 100.0%
2018-08-29 10:08:35.7: Dispatcher cycle time: 0.1 sec.
2018-08-29 10:08:35.7: Acquire data for Observation RX_J0524+42...
2018-08-29 10:08:35.7:   (belongs to Project Walker, Plan RX_J0524+42)
2018-08-29 10:08:35.7: Doing 30 sets
2018-08-29 10:08:43.2: Send Observation RX_J0524+42 to ACP Sequencer
2018-08-29 10:27:15.3: ACP ERROR: Run failed for unknown reason (see ACP run log)
2018-08-29 10:27:15.3: ACP data acquisition failed for Observation RX_J0524+42.
2018-08-29 10:27:15.3:   (Observation RX_J0524+42 interrupted by acquisition process failure.)
2018-08-29 10:27:19.3: -- Weather Unsafe --
2018-08-29 10:58:08.3: ++ Weather Safe ++
2018-08-29 10:58:09.3: ++ Observatory Startup ++
2018-08-29 10:58:09.3: Start ACP Sequencer's StartupObs script
2018-08-29 10:58:25.1: Open the observatory dome/roof
2018-08-29 10:59:55.3: Observatory startup succeeded, and dome opened successfully
2018-08-29 11:00:02.5: ++ Auto Focus ++
2018-08-29 11:00:02.6: Doing initial autofocus.
2018-08-29 11:00:02.6: Start special ACP AutoFocus script for scheduler
2018-08-29 11:04:13.0: Next periodic autofocus ASAP after 29-Aug-2018 14:04:00 UTC
2018-08-29 11:04:16.5: Rising plan delay enabled for Plan CB_32:
2018-08-29 11:04:16.5:     402 min. remaining to meet strict constraints.
2018-08-29 11:04:16.5:     37 min. remaining for the plan to fit before dawn.
2018-08-29 11:04:16.5:     deferred (rising) for 37 min, until 29-Aug-2018 11:41:18 UTC
2018-08-29 11:04:16.7: Rising plan delay enabled for Plan DN_Lyn:
2018-08-29 11:04:16.7:     620 min. remaining to meet strict constraints.
2018-08-29 11:04:16.7:     24 min. remaining for the plan to fit before dawn.
2018-08-29 11:04:16.7:     deferred (rising) for 22 min, until 29-Aug-2018 11:26:38 UTC
2018-08-29 11:04:16.7: Rising plan delay enabled for Plan MP_Gem:
2018-08-29 11:04:16.7:     524 min. remaining to meet strict constraints.
2018-08-29 11:04:16.7:     6 min. remaining for the plan to fit before dawn.
2018-08-29 11:04:16.7:     deferred (rising) for 3 min, until 29-Aug-2018 11:07:45 UTC
2018-08-29 11:04:16.7: Rising plan delay enabled for Plan T_Ori:
2018-08-29 11:04:16.7:     383 min. remaining to meet strict constraints.
2018-08-29 11:04:16.7:     30 min. remaining for the plan to fit before dawn.
2018-08-29 11:04:16.7:     deferred (rising) for 29 min, until 29-Aug-2018 11:33:36 UTC
2018-08-29 11:04:16.8: Dispatcher cycle time: 1.2 sec.
2018-08-29 11:04:16.8: Acquire data for Observation CM_Cas...
2018-08-29 11:04:16.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
2018-08-29 11:04:17.2: Send Observation CM_Cas to ACP Sequencer
2018-08-29 11:21:23.7: Acquisition time: 1026.4974029 sec.
2018-08-29 11:21:23.7: Data for Observation CM_Cas of Plan CM_Cas acquired successfully.
2018-08-29 11:21:23.7: Plan CM_Cas completed successfully.
2018-08-29 11:21:23.7: Image Efficiency: 37.9%
2018-08-29 11:21:23.7: Cycle Efficiency: 99.9%
2018-08-29 11:23:06.6: Rising plan delay enabled for Plan V0965_Per:
2018-08-29 11:23:06.6:     323 min. remaining to meet strict constraints.
2018-08-29 11:23:06.6:     1 min. remaining for the plan to fit before dawn.
2018-08-29 11:23:06.6:     ending rising plan delay. Plan is now eligible.
2018-08-29 11:23:06.6: Dispatcher cycle time: 0.0 sec.
2018-08-29 11:23:06.6: Acquire data for Observation V0965_Per...
2018-08-29 11:23:06.6:   (belongs to Project Nova_Monitoring, Plan V0965_Per)
2018-08-29 11:23:06.6: Send Observation V0965_Per to ACP Sequencer
2018-08-29 11:43:58.5: Acquisition time: 1251.9021988 sec.
2018-08-29 11:43:58.5: Data for Observation V0965_Per of Plan V0965_Per acquired successfully.
2018-08-29 11:43:58.5: Plan V0965_Per completed successfully.
2018-08-29 11:43:58.5: Image Efficiency: 71.9%
2018-08-29 11:43:58.5: Cycle Efficiency: 100.0%
2018-08-29 12:00:35.9: ++ Dawn Flats ++
2018-08-29 12:00:35.9: ERROR: Dawn flats requested, but no SchedulerDawnFlats.txt plan file found
2018-08-29 12:01:13.7: -- Observatory Shutdown --
2018-08-29 12:01:13.7: Start ACP Sequencer's ShutdownObs script
2018-08-29 12:21:02.1: -- Weather Unsafe --
2018-08-29 13:51:22.1: Dispatcher stopped at 29-Aug-2018 13:51:22 UTC
2018-08-29 13:51:22.1: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2018-08-30 02:29:11.0: ++ Weather Safe ++
2018-08-30 02:38:42.6: -- Weather Unsafe --
2018-08-30 03:48:09.1: ++ Weather Safe ++
2018-08-30 11:08:40.2: -- Weather Unsafe --
2018-08-31 02:49:13.0: ++ Weather Safe ++
2018-08-31 03:12:33.2: -- Weather Unsafe --
2018-08-31 05:37:16.0: ++ Weather Safe ++
2018-08-31 06:58:32.3: -- Weather Unsafe --
2018-08-31 07:45:58.2: ++ Weather Safe ++
2018-08-31 12:17:55.1: -- Weather Unsafe --
2018-09-01 02:22:27.9: ++ Weather Safe ++
2018-09-01 07:01:33.1: -- Weather Unsafe --
2018-09-01 07:34:35.0: ++ Weather Safe ++
2018-09-01 07:55:03.6: -- Weather Unsafe --
2018-09-01 08:44:33.8: ++ Weather Safe ++
2018-09-01 08:46:57.2: -- Weather Unsafe --
2018-09-01 10:44:02.9: ++ Weather Safe ++
2018-09-01 12:23:31.2: -- Weather Unsafe --
2018-09-02 04:32:30.8: ++ Weather Safe ++
2018-09-02 11:26:25.3: -- Weather Unsafe --
2018-09-02 12:13:28.0: ++ Weather Safe ++
2018-09-02 12:25:00.1: -- Weather Unsafe --
2018-09-03 02:53:02.9: ++ Weather Safe ++
2018-09-03 12:19:47.1: -- Weather Unsafe --
2018-09-04 02:13:29.6: Dispatcher started at 04-Sep-2018 02:13:29 UTC
2018-09-04 02:13:29.8: Run statistics:
2018-09-04 02:13:29.8:   Observations:   53
2018-09-04 02:13:29.8:   Considered:     30
2018-09-04 02:13:29.8:   Completed:      15
2018-09-04 02:13:29.8:   Skipped:        10
2018-09-04 02:13:29.8:   Never Eligible: 23
2018-09-04 02:13:29.8:   Failed:         5
Log closed at Tue, Sep 04 2018 02:13:29 UTC (actual time)