Log opened at Sun, Nov 11 2018 04:33:11 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2018-11-11 04:33:11.0: Internal clock initialized at 11-Nov-2018 04:33:11 UTC
2018-11-11 04:33:11.0: Loaded Constraint plugin AirMass
2018-11-11 04:33:11.0: Loaded Constraint plugin AirmassRange
2018-11-11 04:33:11.0: Loaded Constraint plugin Horizon
2018-11-11 04:33:11.0: Loaded Constraint plugin HourAngle
2018-11-11 04:33:11.0: Loaded Constraint plugin MoonAvoid
2018-11-11 04:33:11.0: Loaded Constraint plugin MoonDown
2018-11-11 04:33:11.0: Loaded Constraint plugin SkyCondition
2018-11-11 04:33:11.1: Loaded Constraint plugin TimeRange
2018-11-11 04:33:11.9: 49 plans are now pending.
2018-11-11 04:33:11.9: Attach ACP sequencer
2018-11-11 04:33:20.3: ++ Weather Safe ++
2018-11-11 04:33:22.0: Sequencer does sky flats
2018-11-11 04:33:25.9: Dispatcher started at 11-Nov-2018 04:33:25 UTC
2018-11-11 04:33:27.9: ++ Observatory Startup ++
2018-11-11 04:33:27.9: Start ACP Sequencer's StartupObs script
2018-11-11 04:33:27.9: Starting with open dome, unsafe weather will cause operator intervention error
2018-11-11 04:33:59.0: Observatory startup succeeded
2018-11-11 04:33:59.4: ++ Auto Focus ++
2018-11-11 04:33:59.4: Doing initial autofocus.
2018-11-11 04:33:59.4: Start special ACP AutoFocus script for scheduler
2018-11-11 04:38:50.6: Next periodic autofocus ASAP after 11-Nov-2018 07:38:20 UTC
2018-11-11 04:38:54.4: Plan AF_And has Monitor Mode. Time to resubmit it.
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan CM_Cas:
2018-11-11 04:38:54.5:     416 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 51 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 49 min, until 11-Nov-2018 05:28:27 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan FU_Cam:
2018-11-11 04:38:54.5:     500 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 141 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 140 min, until 11-Nov-2018 06:59:06 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan FW_Cam:
2018-11-11 04:38:54.5:     512 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 148 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 147 min, until 11-Nov-2018 07:05:55 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan FY_Cam:
2018-11-11 04:38:54.5:     505 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 154 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 152 min, until 11-Nov-2018 07:11:37 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan GSC_4030_204:
2018-11-11 04:38:54.5:     358 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     477 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 14 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 14 min, until 11-Nov-2018 04:53:34 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan HI_Cam:
2018-11-11 04:38:54.5:     568 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 213 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 211 min, until 11-Nov-2018 08:10:49 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan HO_Cam:
2018-11-11 04:38:54.5:     597 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 250 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 248 min, until 11-Nov-2018 08:47:52 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan HQ_Aur:
2018-11-11 04:38:54.5:     600 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 267 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 265 min, until 11-Nov-2018 09:04:51 UTC
2018-11-11 04:38:54.5: Rising plan delay enabled for Plan HX_Aur:
2018-11-11 04:38:54.5:     567 min. remaining to meet strict constraints.
2018-11-11 04:38:54.5:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.5:     but only 240 min till mid-Plan transit.
2018-11-11 04:38:54.5:     deferred (rising) for 238 min, until 11-Nov-2018 08:37:40 UTC
2018-11-11 04:38:54.6: Rising plan delay enabled for Plan QZ_Aur:
2018-11-11 04:38:54.6:     566 min. remaining to meet strict constraints.
2018-11-11 04:38:54.6:     443 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.6:     but only 257 min till mid-Plan transit.
2018-11-11 04:38:54.6:     deferred (rising) for 253 min, until 11-Nov-2018 08:52:23 UTC
2018-11-11 04:38:54.7: Rising plan delay enabled for Plan V0603_Per:
2018-11-11 04:38:54.7:     497 min. remaining to meet strict constraints.
2018-11-11 04:38:54.7:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.7:     but only 165 min till mid-Plan transit.
2018-11-11 04:38:54.7:     deferred (rising) for 164 min, until 11-Nov-2018 07:22:57 UTC
2018-11-11 04:38:54.7: Rising plan delay enabled for Plan V0965_Per:
2018-11-11 04:38:54.7:     436 min. remaining to meet strict constraints.
2018-11-11 04:38:54.7:     458 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.7:     but only 127 min till mid-Plan transit.
2018-11-11 04:38:54.7:     deferred (rising) for 125 min, until 11-Nov-2018 06:44:28 UTC
2018-11-11 04:38:54.8: Rising plan delay enabled for Plan WZ_Per:
2018-11-11 04:38:54.8:     476 min. remaining to meet strict constraints.
2018-11-11 04:38:54.8:     461 min. remaining for the plan to fit before dawn.
2018-11-11 04:38:54.8:     but only 159 min till mid-Plan transit.
2018-11-11 04:38:54.8:     deferred (rising) for 157 min, until 11-Nov-2018 07:16:45 UTC
2018-11-11 04:38:54.9: Dispatcher cycle time: 1.8 sec.
2018-11-11 04:38:54.9: Acquire data for Observation AF_And...
2018-11-11 04:38:54.9:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2018-11-11 04:38:54.9: Send Observation AF_And to ACP Sequencer
2018-11-11 05:18:00.7: Acquisition time: 2345.8073201 sec.
2018-11-11 05:18:00.7: Data for Observation AF_And of Plan AF_And acquired successfully.
2018-11-11 05:18:00.7: Plan AF_And completed successfully.
2018-11-11 05:18:00.7: Image Efficiency: 55.4%
2018-11-11 05:18:00.7: Cycle Efficiency: 99.9%
2018-11-11 05:18:01.5: Dispatcher cycle time: 0.2 sec.
2018-11-11 05:18:01.5: Acquire data for Observation GSC_3649-0825...
2018-11-11 05:18:01.5:   (belongs to Project Staels, Plan GSC_3649-0825)
2018-11-11 05:18:01.5: Send Observation GSC_3649-0825 to ACP Sequencer
2018-11-11 05:24:01.3: Acquisition time: 359.799032 sec.
2018-11-11 05:24:01.3: Data for Observation GSC_3649-0825 of Plan GSC_3649-0825 acquired successfully.
2018-11-11 05:24:01.3: Plan GSC_3649-0825 completed successfully.
2018-11-11 05:24:01.3: Image Efficiency: 37.5%
2018-11-11 05:24:01.3: Cycle Efficiency: 100.0%
2018-11-11 05:24:05.8: Dispatcher cycle time: 0.2 sec.
2018-11-11 05:24:05.8: Acquire data for Observation GSC_4030_204...
2018-11-11 05:24:05.8:   (belongs to Project Staels, Plan GSC_4030_204)
2018-11-11 05:24:08.9: Send Observation GSC_4030_204 to ACP Sequencer
2018-11-11 05:27:08.7: Acquisition time: 179.8215158 sec.
2018-11-11 05:27:08.7: Data for Observation GSC_4030_204 of Plan GSC_4030_204 acquired successfully.
2018-11-11 05:27:08.7: Plan GSC_4030_204 completed successfully.
2018-11-11 05:27:08.7: Image Efficiency: 16.7%
2018-11-11 05:27:08.7: Cycle Efficiency: 99.9%
2018-11-11 05:27:09.5: Dispatcher cycle time: 0.1 sec.
2018-11-11 05:27:09.5: Acquire data for Observation V0809_Cep...
2018-11-11 05:27:09.5:   (belongs to Project Nova_Monitoring, Plan V0809_Cep)
2018-11-11 05:27:14.0: Send Observation V0809_Cep to ACP Sequencer
2018-11-11 06:00:58.5: Acquisition time: 2024.4779558 sec.
2018-11-11 06:00:58.5: Data for Observation V0809_Cep of Plan V0809_Cep acquired successfully.
2018-11-11 06:00:58.5: Plan V0809_Cep completed successfully.
2018-11-11 06:00:58.5: Image Efficiency: 69.1%
2018-11-11 06:00:58.5: Cycle Efficiency: 100.0%
2018-11-11 06:00:59.0: Rising plan delay enabled for Plan CB_32:
2018-11-11 06:00:59.1:     414 min. remaining to meet strict constraints.
2018-11-11 06:00:59.1:     392 min. remaining for the plan to fit before dawn.
2018-11-11 06:00:59.1:     but only 198 min till mid-Plan transit.
2018-11-11 06:00:59.1:     deferred (rising) for 198 min, until 11-Nov-2018 09:19:25 UTC
2018-11-11 06:00:59.1: Rising plan delay enabled for Plan DN_Lyn:
2018-11-11 06:00:59.1:     632 min. remaining to meet strict constraints.
2018-11-11 06:00:59.1:     379 min. remaining for the plan to fit before dawn.
2018-11-11 06:00:59.1:     but only 307 min till mid-Plan transit.
2018-11-11 06:00:59.1:     deferred (rising) for 305 min, until 11-Nov-2018 11:06:17 UTC
2018-11-11 06:00:59.2: Dispatcher cycle time: 0.1 sec.
2018-11-11 06:00:59.2: Acquire data for Observation NGC_7790...
2018-11-11 06:00:59.2:   (belongs to Project AAVSO_Monitoring, Plan NGC_7790)
2018-11-11 06:00:59.2: Send Observation NGC_7790 to ACP Sequencer
2018-11-11 06:20:37.2: Acquisition time: 1178.0048691 sec.
2018-11-11 06:20:37.2: Data for Observation NGC_7790 of Plan NGC_7790 acquired successfully.
2018-11-11 06:20:37.2: Plan NGC_7790 completed successfully.
2018-11-11 06:20:37.2: Image Efficiency: 39.5%
2018-11-11 06:20:37.2: Cycle Efficiency: 100.0%
2018-11-11 06:20:37.8: Rising plan delay enabled for Plan MP_Gem:
2018-11-11 06:20:37.8:     517 min. remaining to meet strict constraints.
2018-11-11 06:20:37.8:     342 min. remaining for the plan to fit before dawn.
2018-11-11 06:20:37.8:     but only 235 min till mid-Plan transit.
2018-11-11 06:20:37.8:     deferred (rising) for 232 min, until 11-Nov-2018 10:12:57 UTC
2018-11-11 06:20:37.9: Rising plan delay enabled for Plan T_Ori:
2018-11-11 06:20:37.9:     376 min. remaining to meet strict constraints.
2018-11-11 06:20:37.9:     366 min. remaining for the plan to fit before dawn.
2018-11-11 06:20:37.9:     but only 174 min till mid-Plan transit.
2018-11-11 06:20:37.9:     deferred (rising) for 173 min, until 11-Nov-2018 09:14:32 UTC
2018-11-11 06:20:37.9: Dispatcher cycle time: 0.1 sec.
2018-11-11 06:20:37.9: Acquire data for Observation CM_Cas...
2018-11-11 06:20:37.9:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan CM_Cas)
2018-11-11 06:20:37.9: Send Observation CM_Cas to ACP Sequencer
2018-11-11 06:38:43.8: Acquisition time: 1085.8243072 sec.
2018-11-11 06:38:43.8: Data for Observation CM_Cas of Plan CM_Cas acquired successfully.
2018-11-11 06:38:43.8: Plan CM_Cas completed successfully.
2018-11-11 06:38:43.8: Image Efficiency: 35.9%
2018-11-11 06:38:43.8: Cycle Efficiency: 100.0%
2018-11-11 06:38:48.8: Rising plan delay enabled for Plan SDSS073224:
2018-11-11 06:38:48.8:     558 min. remaining to meet strict constraints.
2018-11-11 06:38:48.8:     314 min. remaining for the plan to fit before dawn.
2018-11-11 06:38:48.8:     but only 256 min till mid-Plan transit.
2018-11-11 06:38:48.8:     deferred (rising) for 252 min, until 11-Nov-2018 10:50:54 UTC
2018-11-11 06:38:48.8: Rising plan delay enabled for Plan V0959_Mon:
2018-11-11 06:38:48.8:     457 min. remaining to meet strict constraints.
2018-11-11 06:38:48.8:     342 min. remaining for the plan to fit before dawn.
2018-11-11 06:38:48.8:     but only 217 min till mid-Plan transit.
2018-11-11 06:38:48.8:     deferred (rising) for 216 min, until 11-Nov-2018 10:14:58 UTC
2018-11-11 06:38:48.8: Dispatcher cycle time: 0.1 sec.
2018-11-11 06:38:48.8: Acquire data for Observation V0524_Cas...
2018-11-11 06:38:48.8:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0524_Cas)
2018-11-11 06:38:49.2: Send Observation V0524_Cas to ACP Sequencer
2018-11-11 06:56:35.1: Acquisition time: 1065.8718721 sec.
2018-11-11 06:56:35.1: Data for Observation V0524_Cas of Plan V0524_Cas acquired successfully.
2018-11-11 06:56:35.1: Plan V0524_Cas completed successfully.
2018-11-11 06:56:35.1: Image Efficiency: 36.6%
2018-11-11 06:56:35.1: Cycle Efficiency: 100.0%
2018-11-11 06:56:39.3: Dispatcher cycle time: 0.1 sec.
2018-11-11 06:56:39.3: Acquire data for Observation V0965_Per...
2018-11-11 06:56:39.3:   (belongs to Project Nova_Monitoring, Plan V0965_Per)
2018-11-11 06:56:39.3: Send Observation V0965_Per to ACP Sequencer
2018-11-11 07:19:27.1: Acquisition time: 1367.7636023 sec.
2018-11-11 07:19:27.1: Data for Observation V0965_Per of Plan V0965_Per acquired successfully.
2018-11-11 07:19:27.1: Plan V0965_Per completed successfully.
2018-11-11 07:19:27.1: Image Efficiency: 65.8%
2018-11-11 07:19:27.1: Cycle Efficiency: 100.0%
2018-11-11 07:19:30.2: Dispatcher cycle time: 0.1 sec.
2018-11-11 07:19:30.2: Acquire data for Observation FW_Cam...
2018-11-11 07:19:30.2:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan FW_Cam)
2018-11-11 07:19:30.2: Send Observation FW_Cam to ACP Sequencer
2018-11-11 07:38:16.6: Acquisition time: 1126.3531784 sec.
2018-11-11 07:38:16.6: Data for Observation FW_Cam of Plan FW_Cam acquired successfully.
2018-11-11 07:38:16.6: Plan FW_Cam completed successfully.
2018-11-11 07:38:16.6: Image Efficiency: 34.6%
2018-11-11 07:38:16.6: Cycle Efficiency: 100.0%
2018-11-11 07:38:19.0: Dispatcher cycle time: 0.1 sec.
2018-11-11 07:38:19.0: Acquire data for Observation V0603_Per...
2018-11-11 07:38:19.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0603_Per)
2018-11-11 07:38:19.1: Send Observation V0603_Per to ACP Sequencer
2018-11-11 12:49:00.4: Dispatcher stopped at 11-Nov-2018 12:49:00 UTC
2018-11-11 12:49:00.4: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2018-11-11 12:49:08.5: RUN FAILED: Scheduler was stopped during run
2018-11-11 12:49:08.5: ACP data acquisition failed for Observation V0603_Per.
2018-11-11 12:49:08.5:   (Observation V0603_Per interrupted by operator or system.)
2018-11-11 13:26:26.7: -- Weather Unsafe --
2018-11-12 13:09:14.1: ++ Weather Safe ++
2018-11-12 13:22:01.2: -- Weather Unsafe --
2018-11-13 00:51:06.4: ++ Weather Safe ++
2018-11-13 13:19:15.4: -- Weather Unsafe --
2018-11-14 00:51:03.5: ++ Weather Safe ++
2018-11-14 01:46:14.1: Dispatcher started at 14-Nov-2018 01:46:14 UTC
2018-11-14 01:46:15.7: Run statistics:
2018-11-14 01:46:15.7:   Observations:   54
2018-11-14 01:46:15.7:   Considered:     20
2018-11-14 01:46:15.7:   Completed:      9
2018-11-14 01:46:15.7:   Skipped:        7
2018-11-14 01:46:15.7:   Never Eligible: 34
2018-11-14 01:46:15.7:   Failed:         4
Log closed at Wed, Nov 14 2018 01:46:15 UTC (actual time)