Log opened at Sat, Feb 09 2019 02:03:02 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-02-09 05:30:00.8: ++ Weather Safe ++
2019-02-09 05:30:01.8: ++ Observatory Startup ++
2019-02-09 05:30:01.8: Start ACP Sequencer's StartupObs script
2019-02-09 05:31:06.9: Open the observatory dome/roof
2019-02-09 05:32:14.1: Observatory startup succeeded, and dome opened successfully
2019-02-09 05:32:14.4: ++ Auto Focus ++
2019-02-09 05:32:14.4: Doing initial autofocus.
2019-02-09 05:32:14.4: Start special ACP AutoFocus script for scheduler
2019-02-09 05:36:28.2: Next periodic autofocus ASAP after 09-Feb-2019 08:36:15 UTC
2019-02-09 05:36:29.6: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan GSC_3649-0825 has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan CB_32 has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan RZ_Leo has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan V0959_Mon has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan V0965_Per has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan M56 has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan T_Ori has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan M67 has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan SDSSJ121027+502735 has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:29.6: Plan AF_And has Monitor Mode. Time to resubmit it.
2019-02-09 05:36:30.7: Rising plan delay enabled for Plan CY_UMa:
2019-02-09 05:36:30.7:     553 min. remaining to meet strict constraints.
2019-02-09 05:36:30.7:     444 min. remaining for the plan to fit before dawn.
2019-02-09 05:36:30.7:     but only 190 min till mid-Plan transit.
2019-02-09 05:36:30.7:     deferred (rising) for 190 min, until 09-Feb-2019 08:47:18 UTC
2019-02-09 05:36:30.7: Rising plan delay enabled for Plan M67:
2019-02-09 05:36:30.7:     283 min. remaining to meet strict constraints.
2019-02-09 05:36:30.7:     444 min. remaining for the plan to fit before dawn.
2019-02-09 05:36:30.7:     but only 65 min till mid-Plan transit.
2019-02-09 05:36:30.7:     deferred (rising) for 65 min, until 09-Feb-2019 06:42:00 UTC
2019-02-09 05:36:30.7: Rising plan delay enabled for Plan RZ_Leo:
2019-02-09 05:36:30.7:     478 min. remaining to meet strict constraints.
2019-02-09 05:36:30.7:     444 min. remaining for the plan to fit before dawn.
2019-02-09 05:36:30.7:     but only 231 min till mid-Plan transit.
2019-02-09 05:36:30.7:     deferred (rising) for 231 min, until 09-Feb-2019 09:27:37 UTC
2019-02-09 05:36:30.8: Rising plan delay enabled for Plan SDSSJ121027+502735:
2019-02-09 05:36:30.8:     586 min. remaining to meet strict constraints.
2019-02-09 05:36:30.8:     444 min. remaining for the plan to fit before dawn.
2019-02-09 05:36:30.8:     but only 264 min till mid-Plan transit.
2019-02-09 05:36:30.8:     deferred (rising) for 264 min, until 09-Feb-2019 10:00:36 UTC
2019-02-09 05:36:31.0: Dispatcher cycle time: 1.3 sec.
2019-02-09 05:36:31.0: Acquire data for Observation QZ_Aur...
2019-02-09 05:36:31.0:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-02-09 05:36:31.7: Send Observation QZ_Aur to ACP Sequencer
2019-02-09 05:38:22.8: ACP data acquisition failed for Observation QZ_Aur.
2019-02-09 05:38:22.8:   (Observation QZ_Aur interrupted by acquisition process failure.)
2019-02-09 05:38:25.2: Dispatcher cycle time: 0.2 sec.
2019-02-09 05:38:25.2: Acquire data for Observation SDSS073224...
2019-02-09 05:38:25.2:   (belongs to Project AGN, Plan SDSS073224)
2019-02-09 05:38:25.2: Send Observation SDSS073224 to ACP Sequencer
2019-02-09 05:51:24.0: ACP data acquisition failed for Observation SDSS073224.
2019-02-09 05:51:24.0:   (Observation SDSS073224 interrupted by acquisition process failure.)
2019-02-09 05:51:27.5: Dispatcher cycle time: 0.1 sec.
2019-02-09 05:51:27.5: Acquire data for Observation MP_Gem...
2019-02-09 05:51:27.5:   (belongs to Project Staels, Plan MP_Gem)
2019-02-09 05:51:27.5: Send Observation MP_Gem to ACP Sequencer
2019-02-09 05:54:55.9: ACP data acquisition failed for Observation MP_Gem.
2019-02-09 05:54:55.9:   (Observation MP_Gem interrupted by acquisition process failure.)
2019-02-09 05:54:58.6: Dispatcher cycle time: 0.5 sec.
2019-02-09 05:54:58.6: Acquire data for Observation CB_32...
2019-02-09 05:54:58.6:   (belongs to Project Staels, Plan CB_32)
2019-02-09 05:54:59.1: Send Observation CB_32 to ACP Sequencer
2019-02-09 05:59:26.2: Acquisition time: 267.100374 sec.
2019-02-09 05:59:26.2: Data for Observation CB_32 of Plan CB_32 acquired successfully.
2019-02-09 05:59:26.2: Plan CB_32 completed successfully.
2019-02-09 05:59:26.2: Image Efficiency: 33.6%
2019-02-09 05:59:26.2: Cycle Efficiency: 99.8%
2019-02-09 05:59:30.0: Dispatcher cycle time: 0.2 sec.
2019-02-09 05:59:30.0: Acquire data for Observation V0959_Mon...
2019-02-09 05:59:30.0:   (belongs to Project Nova_Monitoring, Plan V0959_Mon)
2019-02-09 05:59:32.1: Send Observation V0959_Mon to ACP Sequencer
2019-02-09 06:07:01.1: ACP data acquisition failed for Observation V0959_Mon.
2019-02-09 06:07:01.1:   (Observation V0959_Mon interrupted by acquisition process failure.)
2019-02-09 06:07:04.7: Dispatcher cycle time: 0.1 sec.
2019-02-09 06:07:04.7: Acquire data for Observation T_Ori...
2019-02-09 06:07:04.7:   (belongs to Project AAVSO_Monitoring, Plan T_Ori)
2019-02-09 06:07:04.7: Doing 3 sets
2019-02-09 06:07:04.7: Send Observation T_Ori to ACP Sequencer
2019-02-09 06:11:28.6: Send Observation T_Ori to ACP Sequencer
2019-02-09 06:14:43.0: Send Observation T_Ori to ACP Sequencer
2019-02-09 06:17:51.8: Acquisition time: 647.0209057 sec.
2019-02-09 06:17:51.8: Data for Observation T_Ori of Plan T_Ori acquired successfully.
2019-02-09 06:17:51.8: Plan T_Ori completed successfully.
2019-02-09 06:17:51.8: Image Efficiency: 41.7%
2019-02-09 06:17:51.8: Cycle Efficiency: 100.0%
2019-02-09 06:42:31.2: Dispatcher cycle time: 0.1 sec.
2019-02-09 06:42:31.2: Acquire data for Observation M67...
2019-02-09 06:42:31.2:   (belongs to Project AAVSO_Monitoring, Plan M67)
2019-02-09 06:42:31.2: Send Observation M67 to ACP Sequencer
2019-02-09 06:46:35.9: ACP data acquisition failed for Observation M67.
2019-02-09 06:46:35.9:   (Observation M67 interrupted by acquisition process failure.)
2019-02-09 08:36:22.0: ++ Auto Focus ++
2019-02-09 08:36:22.0: Doing periodic autofocus.
2019-02-09 08:36:22.0: Start special ACP AutoFocus script for scheduler
2019-02-09 08:39:47.4: Next periodic autofocus ASAP after 09-Feb-2019 11:39:34 UTC
2019-02-09 08:47:20.8: Dispatcher cycle time: 0.1 sec.
2019-02-09 08:47:20.8: Acquire data for Observation CY_UMa...
2019-02-09 08:47:20.8:   (belongs to Project Walker, Plan CY_UMa)
2019-02-09 08:47:20.8: Doing 20 sets
2019-02-09 08:47:20.9: Send Observation CY_UMa to ACP Sequencer
2019-02-09 09:07:45.0: ACP data acquisition failed for Observation CY_UMa.
2019-02-09 09:07:45.0:   (Observation CY_UMa interrupted by acquisition process failure.)
2019-02-09 09:24:02.0: Rising plan delay enabled for Plan V0336_Ser:
2019-02-09 09:24:02.0:     430 min. remaining to meet strict constraints.
2019-02-09 09:24:02.0:     199 min. remaining for the plan to fit before dawn.
2019-02-09 09:24:02.0:     deferred (rising) for 197 min, until 09-Feb-2019 12:42:01 UTC
2019-02-09 09:27:48.7: Dispatcher cycle time: 0.1 sec.
2019-02-09 09:27:48.7: Acquire data for Observation RZ_Leo...
2019-02-09 09:27:48.7:   (belongs to Project Walker, Plan RZ_Leo)
2019-02-09 09:27:48.7: Send Observation RZ_Leo to ACP Sequencer
2019-02-09 09:30:40.5: ACP data acquisition failed for Observation RZ_Leo.
2019-02-09 09:30:40.5:   (Observation RZ_Leo interrupted by acquisition process failure.)
2019-02-09 09:40:23.7: Rising plan delay enabled for Plan Mkn_501:
2019-02-09 09:40:23.7:     606 min. remaining to meet strict constraints.
2019-02-09 09:40:23.7:     200 min. remaining for the plan to fit before dawn.
2019-02-09 09:40:23.7:     deferred (rising) for 200 min, until 09-Feb-2019 13:00:46 UTC
2019-02-09 09:44:07.7: -- Weather Unsafe --
2019-02-09 10:33:06.1: ++ Weather Safe ++
2019-02-09 10:33:07.1: ++ Observatory Startup ++
2019-02-09 10:33:07.2: Start ACP Sequencer's StartupObs script
2019-02-09 10:33:24.0: Open the observatory dome/roof
2019-02-09 10:34:47.1: Observatory startup succeeded, and dome opened successfully
2019-02-09 10:34:48.8: ++ Auto Focus ++
2019-02-09 10:34:48.8: Doing initial autofocus.
2019-02-09 10:34:48.8: Start special ACP AutoFocus script for scheduler
2019-02-09 10:40:21.4: Next periodic autofocus ASAP after 09-Feb-2019 13:39:51 UTC
2019-02-09 10:40:23.4: Dispatcher cycle time: 1.1 sec.
2019-02-09 10:40:23.4: Acquire data for Observation SDSSJ121027+502735...
2019-02-09 10:40:23.4:   (belongs to Project NMSU, Plan SDSSJ121027+502735)
2019-02-09 10:40:24.4: Send Observation SDSSJ121027+502735 to ACP Sequencer
2019-02-09 10:42:42.5: ACP data acquisition failed for Observation SDSSJ121027+502735.
2019-02-09 10:42:42.5:   (Observation SDSSJ121027+502735 interrupted by acquisition process failure.)
2019-02-09 10:48:37.1: Rising plan delay enabled for Plan V1015_Her:
2019-02-09 10:48:37.1:     346 min. remaining to meet strict constraints.
2019-02-09 10:48:37.1:     132 min. remaining for the plan to fit before dawn.
2019-02-09 10:48:37.1:     deferred (rising) for 132 min, until 09-Feb-2019 13:00:46 UTC
2019-02-09 12:04:01.5: Rising plan delay enabled for Plan V841_Oph:
2019-02-09 12:04:01.5:     270 min. remaining to meet strict constraints.
2019-02-09 12:04:01.5:     56 min. remaining for the plan to fit before dawn.
2019-02-09 12:04:01.5:     deferred (rising) for 56 min, until 09-Feb-2019 13:00:46 UTC
2019-02-09 12:19:50.8: Rising plan delay enabled for Plan M56:
2019-02-09 12:19:50.8:     254 min. remaining to meet strict constraints.
2019-02-09 12:19:50.8:     40 min. remaining for the plan to fit before dawn.
2019-02-09 12:19:50.8:     deferred (rising) for 40 min, until 09-Feb-2019 13:00:46 UTC
2019-02-09 12:28:24.6: Rising plan delay enabled for Plan IRAS_19067+2158:
2019-02-09 12:28:24.6:     246 min. remaining to meet strict constraints.
2019-02-09 12:28:24.6:     15 min. remaining for the plan to fit before dawn.
2019-02-09 12:28:24.6:     deferred (rising) for 13 min, until 09-Feb-2019 12:42:01 UTC
2019-02-09 12:29:29.9: Rising plan delay enabled for Plan NSVS_11201899:
2019-02-09 12:29:29.9:     245 min. remaining to meet strict constraints.
2019-02-09 12:29:29.9:     14 min. remaining for the plan to fit before dawn.
2019-02-09 12:29:29.9:     deferred (rising) for 12 min, until 09-Feb-2019 12:42:01 UTC
2019-02-09 12:36:33.7: Rising plan delay enabled for Plan IRAS_19183+2339:
2019-02-09 12:36:33.7:     238 min. remaining to meet strict constraints.
2019-02-09 12:36:33.7:     7 min. remaining for the plan to fit before dawn.
2019-02-09 12:36:33.7:     deferred (rising) for 5 min, until 09-Feb-2019 12:42:01 UTC
2019-02-09 12:42:25.4: Rising plan delay enabled for Plan IRAS_19067+2158:
2019-02-09 12:42:25.4:     232 min. remaining to meet strict constraints.
2019-02-09 12:42:25.4:     1 min. remaining for the plan to fit before dawn.
2019-02-09 12:42:25.4:     ending rising plan delay. Plan is now eligible.
2019-02-09 12:42:25.4: Rising plan delay enabled for Plan IRAS_19183+2339:
2019-02-09 12:42:25.4:     232 min. remaining to meet strict constraints.
2019-02-09 12:42:25.4:     1 min. remaining for the plan to fit before dawn.
2019-02-09 12:42:25.4:     ending rising plan delay. Plan is now eligible.
2019-02-09 12:42:25.4: Rising plan delay enabled for Plan NSVS_11201899:
2019-02-09 12:42:25.4:     232 min. remaining to meet strict constraints.
2019-02-09 12:42:25.4:     1 min. remaining for the plan to fit before dawn.
2019-02-09 12:42:25.4:     ending rising plan delay. Plan is now eligible.
2019-02-09 12:42:25.4: Rising plan delay enabled for Plan V0336_Ser:
2019-02-09 12:42:25.4:     232 min. remaining to meet strict constraints.
2019-02-09 12:42:25.4:     1 min. remaining for the plan to fit before dawn.
2019-02-09 12:42:25.4:     ending rising plan delay. Plan is now eligible.
2019-02-09 12:42:25.5: Dispatcher cycle time: 0.1 sec.
2019-02-09 12:42:25.5: Acquire data for Observation V0336_Ser...
2019-02-09 12:42:25.5:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0336_Ser)
2019-02-09 12:42:25.5: Send Observation V0336_Ser to ACP Sequencer
2019-02-09 12:59:47.2: ACP data acquisition failed for Observation V0336_Ser.
2019-02-09 12:59:47.2:   (Observation V0336_Ser interrupted by acquisition process failure.)
2019-02-09 13:15:18.9: ++ Dawn Flats ++
2019-02-09 13:15:18.9: Start ACP AutoFlat script for Dawn flats
2019-02-09 13:48:45.9: ** ACP script "AutoFlat" failed:
2019-02-09 13:48:45.9: **Script Error**
Source:   ACP
Message:  The script was aborted.
Location: line 2303 column 5.

2019-02-09 13:48:45.9: -- Weather Unsafe --
2019-02-09 13:49:19.2: -- Observatory Shutdown --
2019-02-09 13:49:20.0: Start ACP Sequencer's ShutdownObs script
2019-02-09 13:49:20.8: WARNING: Request sent while Weather Safety Script is running
2019-02-09 13:50:21.0: WARNING: Request sent while Weather Safety Script is running
2019-02-09 13:54:58.8: Dispatcher stopped at 09-Feb-2019 13:54:58 UTC
2019-02-09 13:54:58.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-02-10 01:17:55.3: ++ Weather Safe ++
2019-02-10 13:58:09.8: -- Weather Unsafe --
2019-02-11 03:35:46.3: ++ Weather Safe ++
2019-02-11 08:23:36.1: -- Weather Unsafe --
2019-02-11 09:46:45.5: ++ Weather Safe ++
2019-02-11 10:43:09.9: -- Weather Unsafe --
2019-02-11 11:14:52.3: ++ Weather Safe ++
2019-02-11 13:47:28.2: -- Weather Unsafe --
2019-02-12 01:57:54.6: ++ Weather Safe ++
2019-02-12 01:59:32.2: Dispatcher started at 12-Feb-2019 01:59:32 UTC
2019-02-12 01:59:34.0: Run statistics:
2019-02-12 01:59:34.0:   Observations:   54
2019-02-12 01:59:34.0:   Considered:     35
2019-02-12 01:59:34.0:   Completed:      8
2019-02-12 01:59:34.0:   Skipped:        5
2019-02-12 01:59:34.0:   Never Eligible: 19
2019-02-12 01:59:34.0:   Failed:         22
Log closed at Tue, Feb 12 2019 01:59:34 UTC (actual time)