Log opened at Fri, Mar 22 2019 02:14:03 UTC (actual time)
Current log level is Verbose
ACP Expert Scheduler version 8.1.1
Full license: Arne Henden
2019-03-22 02:14:05.0: ++ Observatory Startup ++
2019-03-22 02:14:05.0: Start ACP Sequencer's StartupObs script
2019-03-22 02:15:08.9: Open the observatory dome/roof
2019-03-22 02:16:17.0: Observatory startup succeeded, and dome opened successfully
2019-03-22 02:16:17.0: ++ Auto Focus ++
2019-03-22 02:16:17.0: Doing initial autofocus.
2019-03-22 02:16:17.0: Start special ACP AutoFocus script for scheduler
2019-03-22 02:21:00.6: Next periodic autofocus ASAP after 22-Mar-2019 05:20:35 UTC
2019-03-22 02:21:00.9: Plan MP_Gem has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:00.9: Plan CY_UMa has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:00.9: Plan V1721_Aql has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:00.9: Plan SDSS073224 has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:00.9: Plan M67 has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:00.9: Plan QZ_Aur has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:00.9: Plan V0336_Ser has Monitor Mode. Time to resubmit it.
2019-03-22 02:21:01.0: Rising plan delay enabled for Plan CY_UMa:
2019-03-22 02:21:01.0:     588 min. remaining to meet strict constraints.
2019-03-22 02:21:01.0:     594 min. remaining for the plan to fit before dawn.
2019-03-22 02:21:01.0:     but only 225 min till mid-Plan transit.
2019-03-22 02:21:01.0:     deferred (rising) for 225 min, until 22-Mar-2019 06:06:07 UTC
2019-03-22 02:21:01.1: Rising plan delay enabled for Plan M67:
2019-03-22 02:21:01.1:     318 min. remaining to meet strict constraints.
2019-03-22 02:21:01.1:     594 min. remaining for the plan to fit before dawn.
2019-03-22 02:21:01.1:     but only 99 min till mid-Plan transit.
2019-03-22 02:21:01.1:     deferred (rising) for 99 min, until 22-Mar-2019 04:00:49 UTC
2019-03-22 02:21:01.1: Rising plan delay enabled for Plan SDSS073224:
2019-03-22 02:21:01.1:     300 min. remaining to meet strict constraints.
2019-03-22 02:21:01.1:     594 min. remaining for the plan to fit before dawn.
2019-03-22 02:21:01.1:     but only 21 min till mid-Plan transit.
2019-03-22 02:21:01.1:     deferred (rising) for 21 min, until 22-Mar-2019 02:42:09 UTC
2019-03-22 02:21:01.4: Dispatcher cycle time: 0.5 sec.
2019-03-22 02:21:01.4: Acquire data for Observation QZ_Aur...
2019-03-22 02:21:01.4:   (belongs to Project AAVSO_Monitoring, Plan QZ_Aur)
2019-03-22 02:21:04.2: Send Observation QZ_Aur to ACP Sequencer
2019-03-22 02:54:26.1: Acquisition time: 2001.8928026 sec.
2019-03-22 02:54:26.1: Data for Observation QZ_Aur of Plan QZ_Aur acquired successfully.
2019-03-22 02:54:26.1: Plan QZ_Aur completed successfully.
2019-03-22 02:54:26.1: Image Efficiency: 69.9%
2019-03-22 02:54:26.1: Cycle Efficiency: 100.0%
2019-03-22 02:54:29.0: Rising plan delay enabled for Plan RZ_Leo:
2019-03-22 02:54:29.0:     478 min. remaining to meet strict constraints.
2019-03-22 02:54:29.0:     560 min. remaining for the plan to fit before dawn.
2019-03-22 02:54:29.0:     but only 231 min till mid-Plan transit.
2019-03-22 02:54:29.0:     deferred (rising) for 231 min, until 22-Mar-2019 06:46:26 UTC
2019-03-22 02:54:29.2: Dispatcher cycle time: 0.3 sec.
2019-03-22 02:54:29.2: Acquire data for Observation MP_Gem...
2019-03-22 02:54:29.2:   (belongs to Project Staels, Plan MP_Gem)
2019-03-22 02:54:29.3: Send Observation MP_Gem to ACP Sequencer
2019-03-22 03:27:18.0: Acquisition time: 1968.7627563 sec.
2019-03-22 03:27:18.0: Data for Observation MP_Gem of Plan MP_Gem acquired successfully.
2019-03-22 03:27:18.0: Plan MP_Gem completed successfully.
2019-03-22 03:27:18.0: Image Efficiency: 73.1%
2019-03-22 03:27:18.0: Cycle Efficiency: 100.0%
2019-03-22 03:27:21.4: Dispatcher cycle time: 0.8 sec.
2019-03-22 03:27:21.4: Acquire data for Observation SDSS073224...
2019-03-22 03:27:21.4:   (belongs to Project AGN, Plan SDSS073224)
2019-03-22 03:27:22.5: Send Observation SDSS073224 to ACP Sequencer
2019-03-22 04:11:43.1: Acquisition time: 2660.5937248 sec.
2019-03-22 04:11:43.1: Data for Observation SDSS073224 of Plan SDSS073224 acquired successfully.
2019-03-22 04:11:43.2: Plan SDSS073224 completed successfully.
2019-03-22 04:11:43.2: Image Efficiency: 90.2%
2019-03-22 04:11:43.2: Cycle Efficiency: 100.0%
2019-03-22 04:11:44.6: Dispatcher cycle time: 0.2 sec.
2019-03-22 04:11:44.6: Acquire data for Observation M67...
2019-03-22 04:11:44.6:   (belongs to Project AAVSO_Monitoring, Plan M67)
2019-03-22 04:11:44.6: Send Observation M67 to ACP Sequencer
2019-03-22 04:16:35.1: ACP ERROR: Run failed for unknown reason (see ACP run log)
2019-03-22 04:16:35.1: ACP data acquisition failed for Observation M67.
2019-03-22 04:16:35.1:   (Observation M67 interrupted by acquisition process failure.)
2019-03-22 04:16:37.4: -- Weather Unsafe --
2019-03-22 04:46:34.7: ++ Weather Safe ++
2019-03-22 04:46:35.7: ++ Observatory Startup ++
2019-03-22 04:46:35.7: Start ACP Sequencer's StartupObs script
2019-03-22 04:46:54.9: Open the observatory dome/roof
2019-03-22 04:48:19.9: Observatory startup succeeded, and dome opened successfully
2019-03-22 04:48:19.9: ++ Auto Focus ++
2019-03-22 04:48:19.9: Doing initial autofocus.
2019-03-22 04:48:19.9: Start special ACP AutoFocus script for scheduler
2019-03-22 04:53:29.8: ** Autofocus failed (n=1), will retry ASAP after 0 min.
2019-03-22 04:53:31.1: Dispatcher cycle time: 1.1 sec.
2019-03-22 04:53:31.1: Acquire data for Observation V0959_Mon...
2019-03-22 04:53:31.1:   (belongs to Project Nova_Monitoring, Plan V0959_Mon)
2019-03-22 04:53:32.1: ++ Auto Focus ++
2019-03-22 04:53:32.1: Doing periodic autofocus.
2019-03-22 04:53:32.1: Start special ACP AutoFocus script for scheduler
2019-03-22 04:56:37.4: ** Autofocus failed (n=2), will retry ASAP after 5 min.
2019-03-22 04:56:37.4: Send Observation V0959_Mon to ACP Sequencer
2019-03-22 05:14:16.1: Acquisition time: 1058.7214822 sec.
2019-03-22 05:14:16.1: Data for Observation V0959_Mon of Plan V0959_Mon acquired successfully.
2019-03-22 05:14:16.1: Plan V0959_Mon completed successfully.
2019-03-22 05:14:16.1: Image Efficiency: 59.4%
2019-03-22 05:14:16.1: Cycle Efficiency: 99.9%
2019-03-22 05:14:18.9: ++ Auto Focus ++
2019-03-22 05:14:18.9: Doing periodic autofocus.
2019-03-22 05:14:18.9: Start special ACP AutoFocus script for scheduler
2019-03-22 05:18:17.2: ** Autofocus failed (n=3), will retry ASAP after 10 min.
2019-03-22 05:18:17.7: Dispatcher cycle time: 0.1 sec.
2019-03-22 05:18:17.7: Acquire data for Observation HO_Cam...
2019-03-22 05:18:17.7:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan HO_Cam)
2019-03-22 05:18:21.1: Send Observation HO_Cam to ACP Sequencer
2019-03-22 05:35:51.5: Acquisition time: 1050.4314706 sec.
2019-03-22 05:35:51.5: Data for Observation HO_Cam of Plan HO_Cam acquired successfully.
2019-03-22 05:35:51.5: Plan HO_Cam completed successfully.
2019-03-22 05:35:51.5: Image Efficiency: 37.1%
2019-03-22 05:35:51.5: Cycle Efficiency: 100.0%
2019-03-22 05:35:52.1: ++ Auto Focus ++
2019-03-22 05:35:52.2: Doing periodic autofocus.
2019-03-22 05:35:52.2: Start special ACP AutoFocus script for scheduler
2019-03-22 05:52:06.2: ** Autofocus failed (n=4), will retry ASAP after 15 min.
2019-03-22 06:06:34.2: Dispatcher cycle time: 0.1 sec.
2019-03-22 06:06:34.2: Acquire data for Observation CY_UMa...
2019-03-22 06:06:34.2:   (belongs to Project Walker, Plan CY_UMa)
2019-03-22 06:06:34.2: Doing 20 sets
2019-03-22 06:06:35.2: Send Observation CY_UMa to ACP Sequencer
2019-03-22 06:48:08.4: Send Observation CY_UMa to ACP Sequencer
2019-03-22 07:27:57.2: Send Observation CY_UMa to ACP Sequencer
2019-03-22 08:07:46.2: Send Observation CY_UMa to ACP Sequencer
2019-03-22 08:47:38.2: Send Observation CY_UMa to ACP Sequencer
2019-03-22 09:27:27.5: Send Observation CY_UMa to ACP Sequencer
2019-03-22 10:07:20.5: Send Observation CY_UMa to ACP Sequencer
2019-03-22 10:47:13.2: Send Observation CY_UMa to ACP Sequencer
2019-03-22 11:27:05.7: Send Observation CY_UMa to ACP Sequencer
2019-03-22 12:06:55.6: Send Observation CY_UMa to ACP Sequencer
2019-03-22 12:11:13.6: ACP data acquisition failed for Observation CY_UMa.
2019-03-22 12:11:13.6:   (Observation CY_UMa interrupted by acquisition process failure.)
2019-03-22 12:11:16.7: ++ Auto Focus ++
2019-03-22 12:11:16.7: Doing periodic autofocus.
2019-03-22 12:11:16.7: Start special ACP AutoFocus script for scheduler
2019-03-22 12:15:16.7: Next periodic autofocus ASAP after 22-Mar-2019 15:15:03 UTC
2019-03-22 12:15:18.9: Rising plan delay enabled for Plan V1721_Aql:
2019-03-22 12:15:18.9:     354 min. remaining to meet strict constraints.
2019-03-22 12:15:18.9:     0 min. remaining for the plan to fit before dawn.
2019-03-22 12:15:18.9:     deferred (rising) for 0 min, until 22-Mar-2019 12:15:22 UTC
2019-03-22 12:15:18.9: Dispatcher cycle time: 1.1 sec.
2019-03-22 12:15:18.9: Acquire data for Observation V0336_Ser...
2019-03-22 12:15:18.9:   (belongs to Project AAVSO_HGUA_Miras_Manual_ver167, Plan V0336_Ser)
2019-03-22 12:15:19.1: Send Observation V0336_Ser to ACP Sequencer
2019-03-22 12:31:00.1: Acquisition time: 940.9913174 sec.
2019-03-22 12:31:00.1: Data for Observation V0336_Ser of Plan V0336_Ser acquired successfully.
2019-03-22 12:31:00.1: Plan V0336_Ser completed successfully.
2019-03-22 12:31:00.1: Image Efficiency: 41.4%
2019-03-22 12:31:00.1: Cycle Efficiency: 99.9%
2019-03-22 12:31:03.7: ++ Dawn Flats ++
2019-03-22 12:31:03.7: Start ACP AutoFlat script for Dawn flats
2019-03-22 13:05:00.1: ** ACP script "AutoFlat" failed:
2019-03-22 13:05:00.1: **Script Error**
Source:   ACP Observatory Control Software
Message:  The script was interrupted before completion.
Location: line 1422 column 13.

2019-03-22 13:05:00.2: -- Weather Unsafe --
2019-03-22 13:05:34.2: -- Observatory Shutdown --
2019-03-22 13:05:34.2: Start ACP Sequencer's ShutdownObs script
2019-03-22 13:05:34.2: WARNING: Request sent while Weather Safety Script is running
2019-03-22 13:31:38.8: Dispatcher stopped at 22-Mar-2019 13:31:38 UTC
2019-03-22 13:31:38.8: **DISPATCHER INTERRUPT LEVEL 1 RECEIVED**
2019-03-23 06:23:08.3: ++ Weather Safe ++
2019-03-23 12:57:22.9: -- Weather Unsafe --
2019-03-24 02:11:56.2: ++ Weather Safe ++
2019-03-24 02:14:16.0: -- Weather Unsafe --
2019-03-24 05:35:56.4: ++ Weather Safe ++
2019-03-24 13:03:52.6: -- Weather Unsafe --
2019-03-25 02:32:22.3: ++ Weather Safe ++
2019-03-25 02:36:48.1: -- Weather Unsafe --
2019-03-25 04:15:05.1: ++ Weather Safe ++
2019-03-25 12:54:29.7: -- Weather Unsafe --
2019-03-26 01:50:36.8: ++ Weather Safe ++
2019-03-26 03:43:30.1: -- Weather Unsafe --
2019-03-27 01:46:54.7: ++ Weather Safe ++
2019-03-27 01:52:28.9: -- Weather Unsafe --
2019-03-27 07:45:09.6: ++ Weather Safe ++
2019-03-27 13:07:27.3: -- Weather Unsafe --
2019-03-28 01:42:57.1: ++ Weather Safe ++
2019-03-28 07:41:52.6: -- Weather Unsafe --
2019-03-28 08:18:10.4: ++ Weather Safe ++
2019-03-28 13:05:47.0: -- Weather Unsafe --
2019-03-29 04:36:15.3: ++ Weather Safe ++
2019-03-29 04:51:00.2: -- Weather Unsafe --
2019-03-29 05:23:08.4: ++ Weather Safe ++
2019-03-29 06:38:02.9: -- Weather Unsafe --
2019-03-29 10:25:44.6: ++ Weather Safe ++
2019-03-29 12:35:35.1: -- Weather Unsafe --
2019-03-30 04:04:49.6: Dispatcher started at 30-Mar-2019 04:04:49 UTC
2019-03-30 04:04:49.9: Run statistics:
2019-03-30 04:04:49.9:   Observations:   54
2019-03-30 04:04:49.9:   Considered:     27
2019-03-30 04:04:49.9:   Completed:      9
2019-03-30 04:04:49.9:   Skipped:        13
2019-03-30 04:04:49.9:   Never Eligible: 27
2019-03-30 04:04:49.9:   Failed:         5
Log closed at Sat, Mar 30 2019 04:04:49 UTC (actual time)