Log opened at Sat, Sep 08 2018 02:13:48 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2018-09-08 02:13:48.4: Internal clock initialized at 08-Sep-2018 02:13:48 UTC
2018-09-08 02:13:48.5: Loaded Constraint plugin AirMass
2018-09-08 02:13:48.5: Loaded Constraint plugin AirmassRange
2018-09-08 02:13:48.5: Loaded Constraint plugin Horizon
2018-09-08 02:13:48.5: Loaded Constraint plugin HourAngle
2018-09-08 02:13:48.5: Loaded Constraint plugin MoonAvoid
2018-09-08 02:13:48.5: Loaded Constraint plugin MoonDown
2018-09-08 02:13:48.5: Loaded Constraint plugin SkyCondition
2018-09-08 02:13:48.5: Loaded Constraint plugin TimeRange
2018-09-08 02:13:49.4: 45 plans are now pending.
2018-09-08 02:13:49.4: Attach ACP sequencer
2018-09-08 02:13:55.7: There is no controllable dome or roof.
2018-09-08 02:13:57.5: Sequencer does sky flats
2018-09-08 02:14:12.2: Weather successfully connected. Wait for valid data...
2018-09-08 02:14:18.7: ++ Weather Safe ++
2018-09-08 02:14:57.3: Dispatcher started at 08-Sep-2018 02:14:57 UTC
2018-09-08 02:15:03.9: ++ Observatory Startup ++
2018-09-08 02:15:03.9: Start ACP Sequencer's StartupObs script
2018-09-08 02:15:32.2: Open the observatory dome/roof
2018-09-08 02:16:28.4: Observatory startup succeeded, and dome opened successfully
2018-09-08 02:17:34.3: ++ Auto Focus ++
2018-09-08 02:17:34.3: Doing initial autofocus.
2018-09-08 02:17:34.3: Start special ACP AutoFocus script for scheduler
2018-09-08 02:21:53.2: Next periodic autofocus ASAP after 08-Sep-2018 05:21:24 UTC
2018-09-08 02:21:57.5: Plan CM_Cas has Monitor Mode. Time to resubmit it.
2018-09-08 02:21:57.5: Plan V0524_Cas has Monitor Mode. Time to resubmit it.
2018-09-08 02:21:57.5: Plan V1904_Cyg has Monitor Mode. Time to resubmit it.
2018-09-08 02:21:57.5: Plan V1907_Cyg has Monitor Mode. Time to resubmit it.
2018-09-08 02:21:57.5: Plan V2481_Cyg has Monitor Mode. Time to resubmit it.
2018-09-08 02:21:57.5: Plan BC_Lac has Monitor Mode. Time to resubmit it.
2018-09-08 02:21:57.5: Rising plan delay enabled for Plan 2MASS_J19395313+0557550:
2018-09-08 02:21:57.5:     308 min. remaining to meet strict constraints.
2018-09-08 02:21:57.5:     553 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.5:     but only 67 min till mid-Plan transit.
2018-09-08 02:21:57.5:     deferred (rising) for 66 min, until 08-Sep-2018 03:28:00 UTC
2018-09-08 02:21:57.6: Rising plan delay enabled for Plan BC_Lac:
2018-09-08 02:21:57.6:     531 min. remaining to meet strict constraints.
2018-09-08 02:21:57.6:     570 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.6:     but only 252 min till mid-Plan transit.
2018-09-08 02:21:57.6:     deferred (rising) for 252 min, until 08-Sep-2018 06:34:52 UTC
2018-09-08 02:21:57.6: Rising plan delay enabled for Plan GSC_3649-0825:
2018-09-08 02:21:57.6:     531 min. remaining to meet strict constraints.
2018-09-08 02:21:57.6:     566 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.6:     but only 300 min till mid-Plan transit.
2018-09-08 02:21:57.6:     deferred (rising) for 300 min, until 08-Sep-2018 07:22:05 UTC
2018-09-08 02:21:57.7: Rising plan delay enabled for Plan IRAS_19067+2158:
2018-09-08 02:21:57.7:     315 min. remaining to meet strict constraints.
2018-09-08 02:21:57.7:     553 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.7:     but only 36 min till mid-Plan transit.
2018-09-08 02:21:57.7:     deferred (rising) for 35 min, until 08-Sep-2018 02:57:07 UTC
2018-09-08 02:21:57.7: Rising plan delay enabled for Plan IRAS_19183+2339:
2018-09-08 02:21:57.7:     330 min. remaining to meet strict constraints.
2018-09-08 02:21:57.7:     553 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.7:     but only 48 min till mid-Plan transit.
2018-09-08 02:21:57.7:     deferred (rising) for 46 min, until 08-Sep-2018 03:08:38 UTC
2018-09-08 02:21:57.7: Rising plan delay enabled for Plan M56:
2018-09-08 02:21:57.7:     338 min. remaining to meet strict constraints.
2018-09-08 02:21:57.7:     553 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.7:     but only 44 min till mid-Plan transit.
2018-09-08 02:21:57.7:     deferred (rising) for 42 min, until 08-Sep-2018 03:04:29 UTC
2018-09-08 02:21:57.7: Rising plan delay enabled for Plan NGC_7790:
2018-09-08 02:21:57.7:     531 min. remaining to meet strict constraints.
2018-09-08 02:21:57.7:     551 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.7:     but only 324 min till mid-Plan transit.
2018-09-08 02:21:57.7:     deferred (rising) for 322 min, until 08-Sep-2018 07:44:40 UTC
2018-09-08 02:21:57.7: Rising plan delay enabled for Plan NSVS_11201899:
2018-09-08 02:21:57.7:     316 min. remaining to meet strict constraints.
2018-09-08 02:21:57.7:     553 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.7:     but only 37 min till mid-Plan transit.
2018-09-08 02:21:57.7:     deferred (rising) for 36 min, until 08-Sep-2018 02:58:01 UTC
2018-09-08 02:21:57.8: Rising plan delay enabled for Plan V0809_Cep:
2018-09-08 02:21:57.8:     531 min. remaining to meet strict constraints.
2018-09-08 02:21:57.8:     538 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.8:     but only 267 min till mid-Plan transit.
2018-09-08 02:21:57.8:     deferred (rising) for 264 min, until 08-Sep-2018 06:46:32 UTC
2018-09-08 02:21:57.8: Rising plan delay enabled for Plan V1721_Aql:
2018-09-08 02:21:57.8:     278 min. remaining to meet strict constraints.
2018-09-08 02:21:57.8:     536 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.8:     but only 26 min till mid-Plan transit.
2018-09-08 02:21:57.8:     deferred (rising) for 22 min, until 08-Sep-2018 02:44:35 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V1722_Aql:
2018-09-08 02:21:57.9:     305 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     536 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 33 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 30 min, until 08-Sep-2018 02:52:15 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V1723_Aql:
2018-09-08 02:21:57.9:     226 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     550 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 13 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 11 min, until 08-Sep-2018 02:33:48 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V1724_Aql:
2018-09-08 02:21:57.9:     242 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     550 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 18 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 16 min, until 08-Sep-2018 02:38:44 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V1904_Cyg:
2018-09-08 02:21:57.9:     473 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     570 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 180 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 180 min, until 08-Sep-2018 05:22:48 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V1907_Cyg:
2018-09-08 02:21:57.9:     490 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     570 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 187 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 187 min, until 08-Sep-2018 05:29:18 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V2481_Cyg:
2018-09-08 02:21:57.9:     460 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     570 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 160 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 160 min, until 08-Sep-2018 05:02:24 UTC
2018-09-08 02:21:57.9: Rising plan delay enabled for Plan V339_Del_TMO61:
2018-09-08 02:21:57.9:     386 min. remaining to meet strict constraints.
2018-09-08 02:21:57.9:     552 min. remaining for the plan to fit before dawn.
2018-09-08 02:21:57.9:     but only 110 min till mid-Plan transit.
2018-09-08 02:21:57.9:     deferred (rising) for 109 min, until 08-Sep-2018 04:11:07 UTC
2018-09-08 02:21:58.0: Dispatcher cycle time: 2.1 sec.
2018-09-08 02:21:58.0: Acquire data for Observation Mkn_501...
2018-09-08 02:21:58.0:   (belongs to Project AAVSO_SXAC_Mkn_501, Plan Mkn_501)
2018-09-08 02:21:58.0: Doing 3 sets
2018-09-08 02:22:05.6: Send Observation Mkn_501 to ACP Sequencer
2018-09-08 02:29:53.6: Send Observation Mkn_501 to ACP Sequencer
2018-09-08 02:37:33.2: Send Observation Mkn_501 to ACP Sequencer
2018-09-08 02:45:14.2: Acquisition time: 1388.620839 sec.
2018-09-08 02:45:14.2: Data for Observation Mkn_501 of Plan Mkn_501 acquired successfully.
2018-09-08 02:45:14.2: Plan Mkn_501 completed successfully.
2018-09-08 02:45:14.2: Image Efficiency: 64.7%
2018-09-08 02:45:14.2: Cycle Efficiency: 99.8%
2018-09-08 02:45:14.9: Rising plan delay enabled for Plan V0524_Cas:
2018-09-08 02:45:14.9:     508 min. remaining to meet strict constraints.
2018-09-08 02:45:14.9:     547 min. remaining for the plan to fit before dawn.
2018-09-08 02:45:14.9:     but only 358 min till mid-Plan transit.
2018-09-08 02:45:14.9:     deferred (rising) for 358 min, until 08-Sep-2018 08:43:31 UTC
2018-09-08 02:45:14.9: Rising plan delay enabled for Plan V1721_Aql:
2018-09-08 02:45:14.9:     254 min. remaining to meet strict constraints.
2018-09-08 02:45:14.9:     513 min. remaining for the plan to fit before dawn.
2018-09-08 02:45:14.9:     but only 2 min till mid-Plan transit.
2018-09-08 02:45:14.9:     ending rising plan delay. Plan is now eligible.
2018-09-08 02:45:15.0: Dispatcher cycle time: 0.1 sec.
2018-09-08 02:45:15.0: Acquire data for Observation V841_Oph...
2018-09-08 02:45:15.0:   (belongs to Project AAVSO_Monitoring, Plan V841_Oph)
2018-09-08 02:45:15.0: Send Observation V841_Oph to ACP Sequencer
2018-09-08 03:04:17.3: ACP ERROR: Run failed for unknown reason (see ACP run log)
2018-09-08 03:04:17.3: ACP data acquisition failed for Observation V841_Oph.
2018-09-08 03:04:17.3:   (Observation V841_Oph interrupted by acquisition process failure.)
2018-09-08 03:04:20.9: -- Weather Unsafe --
2018-09-08 05:46:22.1: ++ Weather Safe ++
2018-09-08 05:46:23.1: ++ Observatory Startup ++
2018-09-08 05:46:23.1: Start ACP Sequencer's StartupObs script
2018-09-08 05:46:42.9: Open the observatory dome/roof
2018-09-08 05:47:42.6: Observatory startup succeeded, and dome opened successfully
2018-09-08 05:47:47.9: ++ Auto Focus ++
2018-09-08 05:47:47.9: Doing initial autofocus.
2018-09-08 05:47:47.9: Start special ACP AutoFocus script for scheduler
2018-09-08 05:51:54.3: Next periodic autofocus ASAP after 08-Sep-2018 08:51:38 UTC
2018-09-08 05:51:56.7: Rising plan delay enabled for Plan AF_And:
2018-09-08 05:51:56.7:     474 min. remaining to meet strict constraints.
2018-09-08 05:51:56.7:     327 min. remaining for the plan to fit before dawn.
2018-09-08 05:51:56.7:     but only 152 min till mid-Plan transit.
2018-09-08 05:51:56.7:     deferred (rising) for 149 min, until 08-Sep-2018 08:21:15 UTC
2018-09-08 05:51:56.7: Rising plan delay enabled for Plan CM_Cas:
2018-09-08 05:51:56.7:     322 min. remaining to meet strict constraints.
2018-09-08 05:51:56.7:     360 min. remaining for the plan to fit before dawn.
2018-09-08 05:51:56.7:     but only 238 min till mid-Plan transit.
2018-09-08 05:51:56.7:     deferred (rising) for 238 min, until 08-Sep-2018 09:50:16 UTC
2018-09-08 05:51:56.7: Rising plan delay enabled for Plan FU_Cam:
2018-09-08 05:51:56.7:     322 min. remaining to meet strict constraints.
2018-09-08 05:51:56.7:     343 min. remaining for the plan to fit before dawn.
2018-09-08 05:51:56.7:     but only 320 min till mid-Plan transit.
2018-09-08 05:51:56.7:     deferred (rising) for 318 min, until 08-Sep-2018 11:10:41 UTC
2018-09-08 05:51:56.8: Rising plan delay enabled for Plan FW_Cam:
2018-09-08 05:51:56.8:     322 min. remaining to meet strict constraints.
2018-09-08 05:51:56.8:     343 min. remaining for the plan to fit before dawn.
2018-09-08 05:51:56.8:     deferred (rising) for 320 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 05:51:56.8: Rising plan delay enabled for Plan FY_Cam:
2018-09-08 05:51:56.8:     322 min. remaining to meet strict constraints.
2018-09-08 05:51:56.8:     343 min. remaining for the plan to fit before dawn.
2018-09-08 05:51:56.8:     deferred (rising) for 320 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 05:51:56.8: Rising plan delay enabled for Plan GSC_4030_204:
2018-09-08 05:51:56.8:     536 min. remaining to meet strict constraints.
2018-09-08 05:51:56.8:     359 min. remaining for the plan to fit before dawn.
2018-09-08 05:51:56.8:     but only 193 min till mid-Plan transit.
2018-09-08 05:51:56.8:     deferred (rising) for 193 min, until 08-Sep-2018 09:05:09 UTC
2018-09-08 05:51:57.0: Dispatcher cycle time: 1.4 sec.
2018-09-08 05:51:57.0: Acquire data for Observation V339_Del_TMO61...
2018-09-08 05:51:57.0:   (belongs to Project AAVSO_Monitoring, Plan V339_Del_TMO61)
2018-09-08 05:51:57.0: Doing 3 sets
2018-09-08 05:51:57.6: Send Observation V339_Del_TMO61 to ACP Sequencer
2018-09-08 05:57:05.1: Send Observation V339_Del_TMO61 to ACP Sequencer
2018-09-08 06:01:58.9: Send Observation V339_Del_TMO61 to ACP Sequencer
2018-09-08 06:06:45.2: Acquisition time: 887.6103589 sec.
2018-09-08 06:06:45.2: Data for Observation V339_Del_TMO61 of Plan V339_Del_TMO61 acquired successfully.
2018-09-08 06:06:45.2: Plan V339_Del_TMO61 completed successfully.
2018-09-08 06:06:45.3: Image Efficiency: 30.4%
2018-09-08 06:06:45.3: Cycle Efficiency: 99.8%
2018-09-08 06:06:49.1: Dispatcher cycle time: 0.3 sec.
2018-09-08 06:06:49.1: Acquire data for Observation U_Aqr...
2018-09-08 06:06:49.1:   (belongs to Project AAVSO_Monitoring, Plan U_Aqr)
2018-09-08 06:06:57.2: Send Observation U_Aqr to ACP Sequencer
2018-09-08 06:37:44.7: Acquisition time: 1847.4800449 sec.
2018-09-08 06:37:44.7: Data for Observation U_Aqr of Plan U_Aqr acquired successfully.
2018-09-08 06:37:44.7: Plan U_Aqr completed successfully.
2018-09-08 06:37:44.7: Image Efficiency: 75.8%
2018-09-08 06:37:44.7: Cycle Efficiency: 100.0%
2018-09-08 06:37:51.2: Rising plan delay enabled for Plan V0603_Per:
2018-09-08 06:37:51.2:     276 min. remaining to meet strict constraints.
2018-09-08 06:37:51.2:     297 min. remaining for the plan to fit before dawn.
2018-09-08 06:37:51.2:     deferred (rising) for 274 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 06:37:51.2: Rising plan delay enabled for Plan V0965_Per:
2018-09-08 06:37:51.2:     276 min. remaining to meet strict constraints.
2018-09-08 06:37:51.2:     294 min. remaining for the plan to fit before dawn.
2018-09-08 06:37:51.2:     but only 260 min till mid-Plan transit.
2018-09-08 06:37:51.2:     deferred (rising) for 258 min, until 08-Sep-2018 10:56:04 UTC
2018-09-08 06:37:51.3: Rising plan delay enabled for Plan WZ_Per:
2018-09-08 06:37:51.3:     276 min. remaining to meet strict constraints.
2018-09-08 06:37:51.3:     297 min. remaining for the plan to fit before dawn.
2018-09-08 06:37:51.3:     deferred (rising) for 274 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 06:37:51.7: Dispatcher cycle time: 0.6 sec.
2018-09-08 06:37:51.7: Acquire data for Observation V1722_Aql...
2018-09-08 06:37:51.7:   (belongs to Project Nova_Monitoring, Plan V1722_Aql)
2018-09-08 06:38:00.4: Send Observation V1722_Aql to ACP Sequencer
2018-09-08 07:12:56.6: Acquisition time: 2096.2536819 sec.
2018-09-08 07:12:56.6: Data for Observation V1722_Aql of Plan V1722_Aql acquired successfully.
2018-09-08 07:12:56.7: Plan V1722_Aql completed successfully.
2018-09-08 07:12:56.7: Image Efficiency: 71.5%
2018-09-08 07:12:56.7: Cycle Efficiency: 100.0%
2018-09-08 07:13:03.0: Rising plan delay enabled for Plan HI_Cam:
2018-09-08 07:13:03.0:     240 min. remaining to meet strict constraints.
2018-09-08 07:13:03.0:     262 min. remaining for the plan to fit before dawn.
2018-09-08 07:13:03.0:     deferred (rising) for 239 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 07:13:03.2: Dispatcher cycle time: 0.2 sec.
2018-09-08 07:13:03.2: Acquire data for Observation V0809_Cep...
2018-09-08 07:13:03.2:   (belongs to Project Nova_Monitoring, Plan V0809_Cep)
2018-09-08 07:13:10.5: Send Observation V0809_Cep to ACP Sequencer
2018-09-08 07:28:58.7: ACP ERROR: Run failed for unknown reason (see ACP run log)
2018-09-08 07:28:58.7: ACP data acquisition failed for Observation V0809_Cep.
2018-09-08 07:28:58.7:   (Observation V0809_Cep interrupted by acquisition process failure.)
2018-09-08 07:29:03.0: -- Weather Unsafe --
2018-09-08 08:25:29.5: ++ Weather Safe ++
2018-09-08 08:25:30.5: ++ Observatory Startup ++
2018-09-08 08:25:30.5: Start ACP Sequencer's StartupObs script
2018-09-08 08:25:47.4: Open the observatory dome/roof
2018-09-08 08:27:15.8: Observatory startup succeeded, and dome opened successfully
2018-09-08 08:27:15.8: ++ Auto Focus ++
2018-09-08 08:27:15.8: Doing initial autofocus.
2018-09-08 08:27:15.8: Start special ACP AutoFocus script for scheduler
2018-09-08 08:31:32.8: Next periodic autofocus ASAP after 08-Sep-2018 11:31:17 UTC
2018-09-08 08:31:34.4: Rising plan delay enabled for Plan HO_Cam:
2018-09-08 08:31:34.4:     162 min. remaining to meet strict constraints.
2018-09-08 08:31:34.4:     184 min. remaining for the plan to fit before dawn.
2018-09-08 08:31:34.4:     deferred (rising) for 160 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 08:31:34.4: Rising plan delay enabled for Plan HQ_Aur:
2018-09-08 08:31:34.4:     162 min. remaining to meet strict constraints.
2018-09-08 08:31:34.4:     184 min. remaining for the plan to fit before dawn.
2018-09-08 08:31:34.4:     deferred (rising) for 160 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 08:31:34.4: Rising plan delay enabled for Plan HX_Aur:
2018-09-08 08:31:34.4:     162 min. remaining to meet strict constraints.
2018-09-08 08:31:34.4:     184 min. remaining for the plan to fit before dawn.
2018-09-08 08:31:34.4:     deferred (rising) for 160 min, until 08-Sep-2018 11:12:13 UTC
2018-09-08 08:31:34.6: Dispatcher cycle time: 1.3 sec.
2018-09-08 08:31:34.6: Acquire data for Observation AF_And...
2018-09-08 08:31:34.6:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2018-09-08 08:31:41.7: Send Observation AF_And to ACP Sequencer
2018-09-08 09:03:39.2: Acquisition time: 1917.4461678 sec.
2018-09-08 09:03:39.2: Data for Observation AF_And of Plan AF_And acquired successfully.
2018-09-08 09:03:39.2: Plan AF_And completed successfully.
2018-09-08 09:03:39.2: Image Efficiency: 67.8%
2018-09-08 09:03:39.2: Cycle Efficiency: 99.9%
2018-09-08 09:03:42.7: Dispatcher cycle time: 0.1 sec.
2018-09-08 09:03:42.7: Acquire data for Observation GSC_3649-0825...
2018-09-08 09:03:42.8:   (belongs to Project Staels, Plan GSC_3649-0825)
2018-09-08 09:03:49.1: Send Observation GSC_3649-0825 to ACP Sequencer
2018-09-08 09:09:18.3: Acquisition time: 329.1761782 sec.
2018-09-08 09:09:18.3: Data for Observation GSC_3649-0825 of Plan GSC_3649-0825 acquired successfully.
2018-09-08 09:09:18.3: Plan GSC_3649-0825 completed successfully.
2018-09-08 09:09:18.3: Image Efficiency: 41.0%
2018-09-08 09:09:18.3: Cycle Efficiency: 100.0%
2018-09-08 09:09:21.5: Dispatcher cycle time: 0.2 sec.
2018-09-08 09:09:21.5: Acquire data for Observation GSC_4030_204...
2018-09-08 09:09:21.5:   (belongs to Project Staels, Plan GSC_4030_204)
2018-09-08 09:09:21.5: Send Observation GSC_4030_204 to ACP Sequencer
2018-09-08 09:12:02.3: Acquisition time: 160.7426824 sec.
2018-09-08 09:12:02.3: Data for Observation GSC_4030_204 of Plan GSC_4030_204 acquired successfully.
2018-09-08 09:12:02.3: Plan GSC_4030_204 completed successfully.
2018-09-08 09:12:02.3: Image Efficiency: 18.6%
2018-09-08 09:12:02.3: Cycle Efficiency: 99.9%
2018-09-08 09:12:12.2: Dispatcher cycle time: 0.5 sec.
2018-09-08 09:12:12.2: Acquire data for Observation NGC_7790...
2018-09-08 09:12:12.2:   (belongs to Project AAVSO_Monitoring, Plan NGC_7790)
2018-09-08 09:12:16.4: Send Observation NGC_7790 to ACP Sequencer
2018-09-08 09:30:12.2: Acquisition time: 1075.8402896 sec.
2018-09-08 09:30:12.2: Data for Observation NGC_7790 of Plan NGC_7790 acquired successfully.
2018-09-08 09:30:12.3: Plan NGC_7790 completed successfully.
2018-09-08 09:30:12.3: Image Efficiency: 43.2%
2018-09-08 09:30:12.3: Cycle Efficiency: 100.0%
2018-09-08 09:30:15.6: Dispatcher cycle time: 1.1 sec.
2018-09-08 09:30:15.6: Acquire data for Observation V0524_Cas...
2018-09-08 09:30:15.6:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0524_Cas)
2018-09-08 09:30:16.3: Send Observation V0524_Cas to ACP Sequencer
2018-09-08 09:45:54.5: Acquisition time: 938.1544478 sec.
2018-09-08 09:45:54.5: Data for Observation V0524_Cas of Plan V0524_Cas acquired successfully.
2018-09-08 09:45:54.5: Plan V0524_Cas completed successfully.
2018-09-08 09:45:54.5: Image Efficiency: 41.5%
2018-09-08 09:45:54.5: Cycle Efficiency: 99.9%
2018-09-08 09:45:58.6: Dispatcher cycle time: 0.1 sec.
2018-09-08 09:45:58.6: Acquire data for Observation RX_J0524+42...
2018-09-08 09:45:58.6:   (belongs to Project Walker, Plan RX_J0524+42)
2018-09-08 09:45:58.6: Doing 30 sets
2018-09-08 09:46:02.4: Send Observation RX_J0524+42 to ACP Sequencer
2018-09-08 10:16:40.4: Send Observation RX_J0524+42 to ACP Sequencer
2018-09-08 10:44:15.8: Send Observation RX_J0524+42 to ACP Sequencer
2018-09-08 11:11:47.7: Send Observation RX_J0524+42 to ACP Sequencer
2018-09-08 11:39:34.0: ***Running Plan RX_J0524+42 (best efforts) completed early:
2018-09-08 11:39:34.0:    Obs RX_J0524+42 would extend past dawn
2018-09-08 11:39:34.0: Acquisition time: 6811.564764 sec.
2018-09-08 11:39:34.0: Data for Observation RX_J0524+42 of Plan RX_J0524+42 acquired successfully.
2018-09-08 11:39:34.0: Plan RX_J0524+42 completed successfully.
2018-09-08 11:39:34.0: Image Efficiency: 70.5%
2018-09-08 11:39:34.0: Cycle Efficiency: 100.0%
2018-09-08 11:39:36.4: ++ Auto Focus ++
2018-09-08 11:39:36.4: Doing periodic autofocus.
2018-09-08 11:39:36.4: Start special ACP AutoFocus script for scheduler
2018-09-08 11:43:40.1: Next periodic autofocus ASAP after 08-Sep-2018 14:43:30 UTC
2018-09-08 11:43:42.9: Rising plan delay enabled for Plan CB_32:
2018-09-08 11:43:42.9:     323 min. remaining to meet strict constraints.
2018-09-08 11:43:42.9:     5 min. remaining for the plan to fit before dawn.
2018-09-08 11:43:42.9:     deferred (rising) for 4 min, until 08-Sep-2018 11:48:33 UTC
2018-09-08 11:43:42.9: Rising plan delay enabled for Plan KH15D:
2018-09-08 11:43:42.9:     720 min. remaining to meet strict constraints.
2018-09-08 11:43:42.9:     8 min. remaining for the plan to fit before dawn.
2018-09-08 11:43:42.9:     deferred (rising) for 8 min, until 08-Sep-2018 11:52:24 UTC
2018-09-08 11:43:43.0: Dispatcher cycle time: 0.2 sec.
2018-09-08 11:43:43.0: Acquire data for Observation CM_Cas...
2018-09-08 11:43:43.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
2018-09-08 11:43:43.3: Send Observation CM_Cas to ACP Sequencer
2018-09-08 11:51:46.6: ACP data acquisition failed for Observation CM_Cas.
2018-09-08 11:51:46.6:   (Observation CM_Cas interrupted by acquisition process failure.)
2018-09-08 12:07:34.8: ++ Dawn Flats ++
2018-09-08 12:07:34.8: Start ACP AutoFlat script for Dawn flats
2018-09-08 12:27:37.6: ** ACP script "AutoFlat" failed:
2018-09-08 12:27:37.6: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1548 column 25.

2018-09-08 12:27:40.1: -- Weather Unsafe --
2018-09-08 12:28:14.7: -- Observatory Shutdown --
2018-09-08 12:28:14.7: Start ACP Sequencer's ShutdownObs script
2018-09-08 12:28:14.7: WARNING: Request sent while Weather Safety Script is running
2018-09-08 13:44:10.3: Dispatcher stopped at 08-Sep-2018 13:44:10 UTC
2018-09-08 13:44:10.3: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2018-09-08 20:58:24.8: Dispatcher started at 08-Sep-2018 20:58:24 UTC
2018-09-08 20:58:26.0: Run statistics:
2018-09-08 20:58:26.0:   Observations:   54
2018-09-08 20:58:26.0:   Considered:     30
2018-09-08 20:58:26.0:   Completed:      10
2018-09-08 20:58:26.0:   Skipped:        14
2018-09-08 20:58:26.0:   Never Eligible: 24
2018-09-08 20:58:26.0:   Failed:         6
Log closed at Sat, Sep 08 2018 20:58:26 UTC (actual time)