Log opened at Wed, Jan 23 2019 03:41:01 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-01-23 03:41:01.3: ++ Observatory Startup ++
2019-01-23 03:41:01.3: Start ACP Sequencer's StartupObs script
2019-01-23 03:42:04.6: Open the observatory dome/roof
2019-01-23 03:43:13.8: Observatory startup succeeded, and dome opened successfully
2019-01-23 03:43:13.8: ++ Auto Focus ++
2019-01-23 03:43:13.9: Doing initial autofocus.
2019-01-23 03:43:13.9: Start special ACP AutoFocus script for scheduler
2019-01-23 03:47:35.8: Next periodic autofocus ASAP after 23-Jan-2019 06:47:15 UTC
2019-01-23 03:47:36.0: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-01-23 03:47:36.0: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-01-23 03:47:36.0: Plan NGC_7790 has Monitor Mode. Time to resubmit it.
2019-01-23 03:47:36.0: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-01-23 03:47:36.0: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-01-23 03:47:36.0: Rising plan delay enabled for Plan CB_32:
2019-01-23 03:47:36.0:     260 min. remaining to meet strict constraints.
2019-01-23 03:47:36.0:     558 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:36.0:     but only 45 min till mid-Plan transit.
2019-01-23 03:47:36.0:     deferred (rising) for 44 min, until 23-Jan-2019 04:32:22 UTC
2019-01-23 03:47:36.0: Rising plan delay enabled for Plan DN_Lyn:
2019-01-23 03:47:36.0:     478 min. remaining to meet strict constraints.
2019-01-23 03:47:36.0:     545 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:36.0:     but only 153 min till mid-Plan transit.
2019-01-23 03:47:36.0:     deferred (rising) for 151 min, until 23-Jan-2019 06:19:14 UTC
2019-01-23 03:47:36.1: Rising plan delay enabled for Plan HQ_Aur:
2019-01-23 03:47:36.1:     364 min. remaining to meet strict constraints.
2019-01-23 03:47:36.1:     545 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:36.1:     but only 31 min till mid-Plan transit.
2019-01-23 03:47:36.1:     deferred (rising) for 30 min, until 23-Jan-2019 04:17:48 UTC
2019-01-23 03:47:36.1: Rising plan delay enabled for Plan HX_Aur:
2019-01-23 03:47:36.1:     332 min. remaining to meet strict constraints.
2019-01-23 03:47:36.1:     545 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:36.1:     but only 4 min till mid-Plan transit.
2019-01-23 03:47:36.1:     deferred (rising) for 3 min, until 23-Jan-2019 03:50:38 UTC
2019-01-23 03:47:37.1: Rising plan delay enabled for Plan MP_Gem:
2019-01-23 03:47:37.1:     383 min. remaining to meet strict constraints.
2019-01-23 03:47:37.1:     528 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:37.1:     but only 101 min till mid-Plan transit.
2019-01-23 03:47:37.1:     deferred (rising) for 98 min, until 23-Jan-2019 05:25:54 UTC
2019-01-23 03:47:37.1: Rising plan delay enabled for Plan QZ_Aur:
2019-01-23 03:47:37.1:     330 min. remaining to meet strict constraints.
2019-01-23 03:47:37.1:     562 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:37.1:     but only 39 min till mid-Plan transit.
2019-01-23 03:47:37.1:     deferred (rising) for 39 min, until 23-Jan-2019 04:26:40 UTC
2019-01-23 03:47:37.2: Rising plan delay enabled for Plan SDSS073224:
2019-01-23 03:47:37.2:     442 min. remaining to meet strict constraints.
2019-01-23 03:47:37.2:     518 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:37.2:     but only 140 min till mid-Plan transit.
2019-01-23 03:47:37.2:     deferred (rising) for 136 min, until 23-Jan-2019 06:03:51 UTC
2019-01-23 03:47:37.2: Rising plan delay enabled for Plan T_Ori:
2019-01-23 03:47:37.2:     242 min. remaining to meet strict constraints.
2019-01-23 03:47:37.2:     551 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:37.2:     but only 40 min till mid-Plan transit.
2019-01-23 03:47:37.2:     deferred (rising) for 39 min, until 23-Jan-2019 04:27:30 UTC
2019-01-23 03:47:37.2: Rising plan delay enabled for Plan V0959_Mon:
2019-01-23 03:47:37.2:     341 min. remaining to meet strict constraints.
2019-01-23 03:47:37.2:     562 min. remaining for the plan to fit before dawn.
2019-01-23 03:47:37.2:     but only 109 min till mid-Plan transit.
2019-01-23 03:47:37.2:     deferred (rising) for 109 min, until 23-Jan-2019 05:37:33 UTC
2019-01-23 03:47:37.4: Dispatcher cycle time: 1.4 sec.
2019-01-23 03:47:37.4: Acquire data for Observation AF_And...
2019-01-23 03:47:37.4:   (belongs to Project AAVSO_UIS01_AF_And, Plan AF_And)
2019-01-23 03:47:38.1: Send Observation AF_And to ACP Sequencer
2019-01-23 04:21:03.4: Acquisition time: 2005.3353075 sec.
2019-01-23 04:21:03.4: Data for Observation AF_And of Plan AF_And acquired successfully.
2019-01-23 04:21:03.5: Plan AF_And completed successfully.
2019-01-23 04:21:03.5: Image Efficiency: 64.8%
2019-01-23 04:21:03.5: Cycle Efficiency: 99.9%
2019-01-23 04:21:06.5: Rising plan delay enabled for Plan CY_UMa:
2019-01-23 04:21:06.5:     695 min. remaining to meet strict constraints.
2019-01-23 04:21:06.5:     528 min. remaining for the plan to fit before dawn.
2019-01-23 04:21:06.5:     but only 333 min till mid-Plan transit.
2019-01-23 04:21:06.5:     deferred (rising) for 333 min, until 23-Jan-2019 09:54:09 UTC
2019-01-23 04:21:06.5: Rising plan delay enabled for Plan M67:
2019-01-23 04:21:06.5:     426 min. remaining to meet strict constraints.
2019-01-23 04:21:06.5:     528 min. remaining for the plan to fit before dawn.
2019-01-23 04:21:06.5:     but only 207 min till mid-Plan transit.
2019-01-23 04:21:06.5:     deferred (rising) for 207 min, until 23-Jan-2019 07:48:51 UTC
2019-01-23 04:21:06.6: Dispatcher cycle time: 1.5 sec.
2019-01-23 04:21:06.6: Acquire data for Observation HX_Aur...
2019-01-23 04:21:06.6:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HX_Aur)
2019-01-23 04:21:07.1: Send Observation HX_Aur to ACP Sequencer
2019-01-23 04:37:55.5: Acquisition time: 1008.3414117 sec.
2019-01-23 04:37:55.5: Data for Observation HX_Aur of Plan HX_Aur acquired successfully.
2019-01-23 04:37:55.5: Plan HX_Aur completed successfully.
2019-01-23 04:37:55.5: Image Efficiency: 38.6%
2019-01-23 04:37:55.5: Cycle Efficiency: 99.9%
2019-01-23 04:37:57.2: Dispatcher cycle time: 1.0 sec.
2019-01-23 04:37:57.2: Acquire data for Observation QZ_Aur...
2019-01-23 04:37:57.2:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-01-23 04:37:57.2: Send Observation QZ_Aur to ACP Sequencer
2019-01-23 05:12:37.0: Acquisition time: 2079.7879118 sec.
2019-01-23 05:12:37.0: Data for Observation QZ_Aur of Plan QZ_Aur acquired successfully.
2019-01-23 05:12:37.0: Plan QZ_Aur completed successfully.
2019-01-23 05:12:37.0: Image Efficiency: 67.3%
2019-01-23 05:12:37.0: Cycle Efficiency: 100.0%
2019-01-23 05:12:40.5: Rising plan delay enabled for Plan KK_UMa:
2019-01-23 05:12:40.5:     678 min. remaining to meet strict constraints.
2019-01-23 05:12:40.5:     460 min. remaining for the plan to fit before dawn.
2019-01-23 05:12:40.5:     but only 320 min till mid-Plan transit.
2019-01-23 05:12:40.5:     deferred (rising) for 319 min, until 23-Jan-2019 10:31:52 UTC
2019-01-23 05:12:40.6: Dispatcher cycle time: 0.2 sec.
2019-01-23 05:12:40.6: Acquire data for Observation CB_32...
2019-01-23 05:12:40.6:   (belongs to Project Staels, Plan CB_32)
2019-01-23 05:12:40.6: Send Observation CB_32 to ACP Sequencer
2019-01-23 05:17:06.2: Acquisition time: 265.5403717 sec.
2019-01-23 05:17:06.2: Data for Observation CB_32 of Plan CB_32 acquired successfully.
2019-01-23 05:17:06.2: Plan CB_32 completed successfully.
2019-01-23 05:17:06.2: Image Efficiency: 33.9%
2019-01-23 05:17:06.2: Cycle Efficiency: 99.9%
2019-01-23 05:17:08.4: Dispatcher cycle time: 0.6 sec.
2019-01-23 05:17:08.4: Acquire data for Observation T_Ori...
2019-01-23 05:17:08.4:   (belongs to Project AAVSO_Monitoring, Plan T_Ori)
2019-01-23 05:17:08.4: Doing 3 sets
2019-01-23 05:17:09.0: Send Observation T_Ori to ACP Sequencer
2019-01-23 05:20:40.6: Send Observation T_Ori to ACP Sequencer
2019-01-23 05:24:01.2: Send Observation T_Ori to ACP Sequencer
2019-01-23 05:27:11.7: Acquisition time: 602.6708438 sec.
2019-01-23 05:27:11.7: Data for Observation T_Ori of Plan T_Ori acquired successfully.
2019-01-23 05:27:11.7: Plan T_Ori completed successfully.
2019-01-23 05:27:11.8: Image Efficiency: 44.7%
2019-01-23 05:27:11.8: Cycle Efficiency: 99.9%
2019-01-23 05:27:14.9: Rising plan delay enabled for Plan MP_Gem:
2019-01-23 05:27:14.9:     283 min. remaining to meet strict constraints.
2019-01-23 05:27:14.9:     428 min. remaining for the plan to fit before dawn.
2019-01-23 05:27:14.9:     but only 2 min till mid-Plan transit.
2019-01-23 05:27:14.9:     ending rising plan delay. Plan is now eligible.
2019-01-23 05:27:15.0: Dispatcher cycle time: 0.1 sec.
2019-01-23 05:27:15.0: Acquire data for Observation MP_Gem...
2019-01-23 05:27:15.0:   (belongs to Project Staels, Plan MP_Gem)
2019-01-23 05:27:15.1: Send Observation MP_Gem to ACP Sequencer
2019-01-23 06:00:57.0: Acquisition time: 2021.9328307 sec.
2019-01-23 06:00:57.0: Data for Observation MP_Gem of Plan MP_Gem acquired successfully.
2019-01-23 06:00:57.0: Plan MP_Gem completed successfully.
2019-01-23 06:00:57.0: Image Efficiency: 71.2%
2019-01-23 06:00:57.0: Cycle Efficiency: 100.0%
2019-01-23 06:01:00.0: Dispatcher cycle time: 1.0 sec.
2019-01-23 06:01:00.0: Acquire data for Observation HQ_Aur...
2019-01-23 06:01:00.0:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HQ_Aur)
2019-01-23 06:01:00.8: Send Observation HQ_Aur to ACP Sequencer
2019-01-23 06:17:30.5: Acquisition time: 989.6813855 sec.
2019-01-23 06:17:30.5: Data for Observation HQ_Aur of Plan HQ_Aur acquired successfully.
2019-01-23 06:17:30.5: Plan HQ_Aur completed successfully.
2019-01-23 06:17:30.5: Image Efficiency: 39.4%
2019-01-23 06:17:30.5: Cycle Efficiency: 99.9%
2019-01-23 06:17:32.8: Dispatcher cycle time: 0.1 sec.
2019-01-23 06:17:32.8: Acquire data for Observation SDSS073224...
2019-01-23 06:17:32.8:   (belongs to Project AGN, Plan SDSS073224)
2019-01-23 06:17:32.8: Send Observation SDSS073224 to ACP Sequencer
2019-01-23 07:02:00.1: Acquisition time: 2667.2337341 sec.
2019-01-23 07:02:00.1: Data for Observation SDSS073224 of Plan SDSS073224 acquired successfully.
2019-01-23 07:02:00.1: Plan SDSS073224 completed successfully.
2019-01-23 07:02:00.1: Image Efficiency: 90.0%
2019-01-23 07:02:00.1: Cycle Efficiency: 100.0%
2019-01-23 07:02:02.4: ++ Auto Focus ++
2019-01-23 07:02:02.5: Doing periodic autofocus.
2019-01-23 07:02:02.5: Start special ACP AutoFocus script for scheduler
2019-01-23 07:05:17.0: Next periodic autofocus ASAP after 23-Jan-2019 10:05:03 UTC
2019-01-23 07:05:17.5: Rising plan delay enabled for Plan RZ_Leo:
2019-01-23 07:05:17.5:     456 min. remaining to meet strict constraints.
2019-01-23 07:05:17.5:     364 min. remaining for the plan to fit before dawn.
2019-01-23 07:05:17.5:     but only 209 min till mid-Plan transit.
2019-01-23 07:05:17.5:     deferred (rising) for 209 min, until 23-Jan-2019 10:34:28 UTC
2019-01-23 07:05:17.6: Dispatcher cycle time: 0.1 sec.
2019-01-23 07:05:17.6: Acquire data for Observation V0959_Mon...
2019-01-23 07:05:17.6:   (belongs to Project Nova_Monitoring, Plan V0959_Mon)
2019-01-23 07:05:19.6: Send Observation V0959_Mon to ACP Sequencer
2019-01-23 07:21:22.1: Acquisition time: 962.5413476 sec.
2019-01-23 07:21:22.1: Data for Observation V0959_Mon of Plan V0959_Mon acquired successfully.
2019-01-23 07:21:22.2: Plan V0959_Mon completed successfully.
2019-01-23 07:21:22.2: Image Efficiency: 65.4%
2019-01-23 07:21:22.2: Cycle Efficiency: 100.0%
2019-01-23 07:21:25.1: Rising plan delay enabled for Plan CV_Vir:
2019-01-23 07:21:25.1:     494 min. remaining to meet strict constraints.
2019-01-23 07:21:25.1:     348 min. remaining for the plan to fit before dawn.
2019-01-23 07:21:25.1:     but only 246 min till mid-Plan transit.
2019-01-23 07:21:25.1:     deferred (rising) for 246 min, until 23-Jan-2019 11:27:55 UTC
2019-01-23 07:21:25.2: Dispatcher cycle time: 0.8 sec.
2019-01-23 07:21:25.2: Acquire data for Observation DN_Lyn...
2019-01-23 07:21:25.2:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan DN_Lyn)
2019-01-23 07:21:26.2: Send Observation DN_Lyn to ACP Sequencer
2019-01-23 07:37:02.3: Acquisition time: 936.0913106 sec.
2019-01-23 07:37:02.3: Data for Observation DN_Lyn of Plan DN_Lyn acquired successfully.
2019-01-23 07:37:02.3: Plan DN_Lyn completed successfully.
2019-01-23 07:37:02.4: Image Efficiency: 41.6%
2019-01-23 07:37:02.4: Cycle Efficiency: 99.9%
2019-01-23 07:49:15.8: Dispatcher cycle time: 0.1 sec.
2019-01-23 07:49:15.8: Acquire data for Observation M67...
2019-01-23 07:49:15.8:   (belongs to Project AAVSO_Monitoring, Plan M67)
2019-01-23 07:49:17.8: Send Observation M67 to ACP Sequencer
2019-01-23 08:23:07.6: Acquisition time: 2029.8228417 sec.
2019-01-23 08:23:07.6: Data for Observation M67 of Plan M67 acquired successfully.
2019-01-23 08:23:07.6: Plan M67 completed successfully.
2019-01-23 08:23:07.6: Image Efficiency: 69.5%
2019-01-23 08:23:07.6: Cycle Efficiency: 100.0%
2019-01-23 08:23:09.8: Rising plan delay enabled for Plan AL_Boo:
2019-01-23 08:23:09.8:     515 min. remaining to meet strict constraints.
2019-01-23 08:23:09.8:     269 min. remaining for the plan to fit before dawn.
2019-01-23 08:23:09.8:     but only 244 min till mid-Plan transit.
2019-01-23 08:23:09.8:     deferred (rising) for 242 min, until 23-Jan-2019 12:25:27 UTC
2019-01-23 09:54:36.7: Dispatcher cycle time: 0.1 sec.
2019-01-23 09:54:36.7: Acquire data for Observation CY_UMa...
2019-01-23 09:54:36.7:   (belongs to Project Walker, Plan CY_UMa)
2019-01-23 09:54:36.7: Doing 20 sets
2019-01-23 09:54:38.1: Send Observation CY_UMa to ACP Sequencer
2019-01-23 10:35:43.1: Send Observation CY_UMa to ACP Sequencer
2019-01-23 11:16:02.8: Send Observation CY_UMa to ACP Sequencer
2019-01-23 11:56:09.6: Send Observation CY_UMa to ACP Sequencer
2019-01-23 12:36:03.0: Send Observation CY_UMa to ACP Sequencer
2019-01-23 13:07:59.2: ACP data acquisition failed for Observation CY_UMa.
2019-01-23 13:07:59.2:   (Observation CY_UMa interrupted by acquisition process failure.)
2019-01-23 13:08:00.9: ++ Auto Focus ++
2019-01-23 13:08:00.9: Doing periodic autofocus.
2019-01-23 13:08:01.0: Start special ACP AutoFocus script for scheduler
2019-01-23 13:11:02.6: Next periodic autofocus ASAP after 23-Jan-2019 16:10:49 UTC
2019-01-23 13:24:59.5: ++ Dawn Flats ++
2019-01-23 13:24:59.5: Start ACP AutoFlat script for Dawn flats
2019-01-23 13:52:04.7: ** ACP script "AutoFlat" failed:
2019-01-23 13:52:04.7: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 1428 column 51.

2019-01-23 13:52:05.8: -- Weather Unsafe --
2019-01-23 13:52:42.2: -- Observatory Shutdown --
2019-01-23 13:52:42.2: Start ACP Sequencer's ShutdownObs script
2019-01-23 13:52:42.2: WARNING: Request sent while Weather Safety Script is running
2019-01-23 13:53:42.4: WARNING: Request sent while Weather Safety Script is running
2019-01-23 14:23:55.8: Dispatcher stopped at 23-Jan-2019 14:23:55 UTC
2019-01-23 14:23:55.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-01-23 14:24:00.0: Dispatcher started at 23-Jan-2019 14:24:00 UTC
2019-01-23 19:21:34.4: Run statistics:
2019-01-23 19:21:34.4:   Observations:   54
2019-01-23 19:21:34.4:   Considered:     34
2019-01-23 19:21:34.4:   Completed:      19
2019-01-23 19:21:34.4:   Skipped:        11
2019-01-23 19:21:34.4:   Never Eligible: 20
2019-01-23 19:21:34.4:   Failed:         4
Log closed at Wed, Jan 23 2019 19:21:34 UTC (actual time)